locked
W3WP.exe consuming CPU 100% every hour in sharepoint 2007 RRS feed

  • Question

  • Hi

    we have got two web front end servers, one app server and two back end clustered SQLs. 12(Twelve) app pools running 50 Web Apps. we are facing performance issues, all the w3wp.exe's utilizing 100% CPU on both WFEs every hour for 2-3 minutes between i-e 12:59 to 13:02 and so on making both the web front servers unavailable for any new web site requests in this time. Apparantly nothing running on servers every hour. 

    Any help would be appreciated. 

    Tuesday, September 27, 2011 12:25 PM

All replies

  • Please double check the recycling options. maybe the processes recycle at this time? Then all caches are empty and have to be refilled.

    If this is not the case and if this would be a support case I would request a performance monitor log with process and thread counters enabled and 3 user dumps taken in 1 minute distance from one of the affected w3wp processes.

    That would allow me to see exactly which threads in the process are causing the issue and what these threads are doing.

     


    Microsoft CSS - This post is provided "AS IS" with no warrenties and confers no rights.
    Tuesday, September 27, 2011 12:38 PM
  • HI,

    Do you have any timer jobs running at this time frame?

    Have you checked this using perfmon and PAL?

    Have you gone through the IIS logs to observe the usage at that time?

     


    Thanks, Rahul Rashu
    Tuesday, September 27, 2011 2:03 PM
  • Hi 
    i have checked the recycling options and nothing there which causes hourly interruption, also looked into the IIS log files and nothing happening there. what i have seen from the ULS logs there is a timer job i-e profile synchronization running every hour and found some old entries/records using stsadm -o sync -listolddatabases which were not getting synchronised, which i have deleted using stsadm -o sync -deleteolddatabases but did not fix the issue. below is few lines from the log file in that peak timings when CPU been utilised 100%, could be helpful to figure out the problem.
    09/30/2011 08:58:56.90 OWSTIMER.EXE (0x2614)                   0x3228 Windows SharePoint Services   Timer                         5utp Verbose Scheduled timer job Scheduled Unpublish, id {D39DBFBA-3FC0-4027-BA5E-7B004E355984} at 30 Sep 2011 08:59:56 +0100 (now is 30 Sep 2011 08:58:56 +0100)  
    09/30/2011 08:58:56.90 OWSTIMER.EXE (0x2614)                   0x3228 Windows SharePoint Services   Timer                         5utp Verbose Scheduled timer job Variations Propagate Page Job Definition, id {8CE3BAF6-C0A2-4ABA-9F4B-4173F813C6EF} at 30 Sep 2011 08:59:56 +0100 (now is 30 Sep 2011 08:58:56 +0100)  
    09/30/2011 08:58:56.90 OWSTIMER.EXE (0x2614)                   0x3228 Windows SharePoint Services   Timer                         5utp Verbose Scheduled timer job Variations Propagate Page Job Definition, id {ACBE09D1-77CD-49D8-B28C-0CE8DA8FB1B6} at 30 Sep 2011 08:59:56 +0100 (now is 30 Sep 2011 08:58:56 +0100)  
    09/30/2011 08:58:57.90 OWSTIMER.EXE (0x2614)                   0x3228 Windows SharePoint Services   Timer                         5utp Verbose Scheduled timer job Variations Propagate Site Job Definition, id {83FCBD11-C5EC-43FA-A1F2-7B8DB090945C} at 30 Sep 2011 08:59:57 +0100 (now is 30 Sep 2011 08:58:57 +0100)  
    09/30/2011 08:58:57.90 OWSTIMER.EXE (0x2614)                   0x3228 Windows SharePoint Services   Timer                         5utp Verbose Scheduled timer job Scheduled Approval, id {67687DF3-1603-4402-8186-A70B6599EEC7} at 30 Sep 2011 08:59:57 +0100 (now is 30 Sep 2011 08:58:57 +0100)  
    09/30/2011 08:58:57.90 OWSTIMER.EXE (0x2614)                   0x3228 Windows SharePoint Services   Timer                         5utp Verbose Scheduled timer job Scheduled Unpublish, id {FA58EAB6-9417-4E3E-ACF3-7243313C64B1} at 30 Sep 2011 08:59:57 +0100 (now is 30 Sep 2011 08:58:57 +0100)  
    09/30/2011 08:58:57.90 OWSTIMER.EXE (0x2614)                   0x3228 Windows SharePoint Services   Timer                         5utp Verbose Scheduled timer job Scheduled Approval, id {A027BE3C-D509-4A16-9364-C0D63A1AB7BE} at 30 Sep 2011 08:59:57 +0100 (now is 30 Sep 2011 08:58:57 +0100)  
    09/30/2011 08:58:57.90 OWSTIMER.EXE (0x2614)                   0x3228 Windows SharePoint Services   Timer                         5utp Verbose Scheduled timer job Scheduled Approval, id {5DB1BD78-BF5A-440F-9E14-5EB9B805EB06} at 30 Sep 2011 08:59:57 +0100 (now is 30 Sep 2011 08:58:57 +0100)  
    09/30/2011 08:58:57.90 OWSTIMER.EXE (0x2614)                   0x3228 Windows SharePoint Services   Timer                         5utp Verbose Scheduled timer job Variations Propagate Page Job Definition, id {F5199B66-DF62-41D9-9C91-70D8DEF3A944} at 30 Sep 2011 08:59:57 +0100 (now is 30 Sep 2011 08:58:57 +0100)  
    09/30/2011 08:58:57.90 OWSTIMER.EXE (0x2614)                   0x3228 Windows SharePoint Services   Timer                         5utp Verbose Scheduled timer job Variations Propagate Site Job Definition, id {21E0FF3A-97D0-4C0E-AF5E-B11DB131F938} at 30 Sep 2011 08:59:57 +0100 (now is 30 Sep 2011 08:58:57 +0100)  
    09/30/2011 08:58:58.90 OWSTIMER.EXE (0x2614)                   0x3228 Windows SharePoint Services   Timer                         5utp Verbose Scheduled timer job Variations Propagate Site Job Definition, id {322DEB96-A669-4F4F-AAC6-E4C0D3C253FC} at 30 Sep 2011 08:59:58 +0100 (now is 30 Sep 2011 08:58:58 +0100)  
    09/30/2011 08:58:58.90 OWSTIMER.EXE (0x2614)                   0x3228 Windows SharePoint Services   Timer                         5utp Verbose Scheduled timer job Scheduled Unpublish, id {C6ED799C-F0B7-43D6-8E8F-79535EB08805} at 30 Sep 2011 08:59:58 +0100 (now is 30 Sep 2011 08:58:58 +0100)  
    09/30/2011 08:58:58.90 OWSTIMER.EXE (0x2614)                   0x3228 Windows SharePoint Services   Timer                         5utp Verbose Scheduled timer job Scheduled Unpublish, id {B5BEBD07-48C2-47A0-9F93-50CB934B6C3C} at 30 Sep 2011 08:59:58 +0100 (now is 30 Sep 2011 08:58:58 +0100)  
    09/30/2011 08:58:59.90 OWSTIMER.EXE (0x2614)                   0x3228 Windows SharePoint Services   Timer                         5utp Verbose Scheduled timer job Scheduled Unpublish, id {C105B95F-3852-446A-BFAD-9FDA4E220A96} at 30 Sep 2011 08:59:59 +0100 (now is 30 Sep 2011 08:58:59 +0100)  
    09/30/2011 08:58:59.90 OWSTIMER.EXE (0x2614)                   0x3228 Windows SharePoint Services   Timer                         5utp Verbose Scheduled timer job Variations Propagate Page Job Definition, id {079DB0B7-5205-43DD-90DA-514649844134} at 30 Sep 2011 08:59:59 +0100 (now is 30 Sep 2011 08:58:59 +0100)  
    09/30/2011 08:58:59.90 OWSTIMER.EXE (0x2614)                   0x3228 Windows SharePoint Services   Timer                         5utp Verbose Scheduled timer job Variations Propagate Site Job Definition, id {90F134AE-4447-471F-9B67-A7391F425B84} at 30 Sep 2011 08:59:59 +0100 (now is 30 Sep 2011 08:58:59 +0100)  
    09/30/2011 08:59:00.33 w3wp.exe (0x378C)                       0x0604 Windows SharePoint Services   General                       6t8b Verbose Looking up context  site http://moss.wingwhill.rgfl.org/Display/Slideshow Images/_w/SDC12755_JPG.jpg in the farm SharePoint_Config  
    09/30/2011 08:59:00.33 w3wp.exe (0x378C)                       0x0604 Windows SharePoint Services   General                       6t8d Verbose Looking up the additional information about the typical site http://moss.wingwhill.rgfl.org/Display/Slideshow Images/_w/SDC12755_JPG.jpg.  
    09/30/2011 08:59:00.33 w3wp.exe (0x378C)                       0x0604 Windows SharePoint Services   General                       6t8f Verbose Site lookup is replacing http://moss.wingwhill.rgfl.org/Display/Slideshow Images/_w/SDC12755_JPG.jpg with the alternate access url http://moss.wingwhill.rgfl.org.  
    09/30/2011 08:59:00.33 w3wp.exe (0x378C)                       0x0604 Windows SharePoint Services   General                       6t8g Verbose Looking up typical site http://moss.wingwhill.rgfl.org/Display/Slideshow Images/_w/SDC12755_JPG.jpg in web application SPWebApplication Name=SharePoint - wingwhill80 Parent=SPWebService.  
    09/30/2011 08:59:00.33 w3wp.exe (0x378C)                       0x0604 Windows SharePoint Services   General                       6t8h Verbose Found typical site / (2d6415ce-877c-4565-b085-4438feca0f88) in web application SPWebApplication Name=SharePoint - wingwhill80 Parent=SPWebService.  
    09/30/2011 08:59:00.90 OWSTIMER.EXE (0x2614)                   0x3228 Windows SharePoint Services   Timer                         5utp Verbose Scheduled timer job Quick Profile Synchronization, id {60184F6B-A09E-4B5C-8C1E-224DBC7878C3} at 30 Sep 2011 09:00:00 +0100 (now is 30 Sep 2011 08:59:00 +0100)  
    09/30/2011 08:59:00.90 OWSTIMER.EXE (0x2614)                   0x3228 Windows SharePoint Services   Timer                         5utp Verbose Scheduled timer job Quick Profile Synchronization, id {B09C0A3C-BBA6-4263-8698-19C77762F419} at 30 Sep 2011 09:00:00 +0100 (now is 30 Sep 2011 08:59:00 +0100)  
    09/30/2011 08:59:00.90 OWSTIMER.EXE (0x2614)                   0x3228 Windows SharePoint Services   Timer                         5utp Verbose Scheduled timer job Quick Profile Synchronization, id {501C16C3-6DAF-4AC6-A105-C10FA0BC7CC3} at 30 Sep 2011 09:00:00 +0100 (now is 30 Sep 2011 08:59:00 +0100)  
    09/30/2011 08:59:00.90 OWSTIMER.EXE (0x2614)                   0x10E4 Windows SharePoint Services   Topology                       88b9 Verbose Determining if the current user is a SharePoint Farm Administrator  
    09/30/2011 08:59:00.90 OWSTIMER.EXE (0x2614)                   0x3228 Windows SharePoint Services   Timer                         5utp Verbose Scheduled timer job Config Refresh, id {6449B735-4026-4CAC-83E6-CE54F0BC62D0} at 30 Sep 2011 08:59:15 +0100 (now is 30 Sep 2011 08:59:00 +0100)  
    09/30/2011 08:59:00.90 OWSTIMER.EXE (0x2614)                   0x3228 Windows SharePoint Services   Timer                         5utp Verbose Scheduled timer job Quick Profile Synchronization, id {281D3E4C-2890-4F57-89FA-34BC947FCEA5} at 30 Sep 2011 09:00:00 +0100 (now is 30 Sep 2011 08:59:00 +0100)  
    Friday, September 30, 2011 11:14 AM
  • 09/30/2011 08:59:00.90 OWSTIMER.EXE (0x2614)                   0x3228 Windows SharePoint Services   Timer                         5utp Verbose Scheduled timer job Quick Profile Synchronization, id {6D817739-E170-4227-B7FB-6ED9ABF423D3} at 30 Sep 2011 09:00:00 +0100 (now is 30 Sep 2011 08:59:00 +0100)  
    09/30/2011 08:59:00.90 OWSTIMER.EXE (0x2614)                   0x3228 Windows SharePoint Services   Timer                         5utp Verbose Scheduled timer job Quick Profile Synchronization, id {F5F1D138-5268-4F1F-A277-93BC1FEB7910} at 30 Sep 2011 09:00:00 +0100 (now is 30 Sep 2011 08:59:00 +0100)  
    09/30/2011 08:59:00.90 OWSTIMER.EXE (0x2614)                   0x3228 Windows SharePoint Services   Timer                         5utp Verbose Scheduled timer job Quick Profile Synchronization, id {B7C8BA57-AD9F-4423-A43D-6A6FA25E8BA9} at 30 Sep 2011 09:00:00 +0100 (now is 30 Sep 2011 08:59:00 +0100)  
    09/30/2011 08:59:00.90 OWSTIMER.EXE (0x2614)                   0x3228 Windows SharePoint Services   Timer                         5utp Verbose Scheduled timer job Quick Profile Synchronization, id {9C325601-FABD-4BF6-B085-E4811226DC54} at 30 Sep 2011 09:00:00 +0100 (now is 30 Sep 2011 08:59:00 +0100)  
    Scheduled timer job Variations Propagate Page Job Definition, id {20B30431-D5D3-4523-BA6F-C7DAAC9FA8B8} at 30 Sep 2011 09:00:01 +0100 (now is 30 Sep 2011 08:59:01 +0100)  
    09/30/2011 08:59:01.90 OWSTIMER.EXE (0x2614)                   0x3228 Windows SharePoint Services   Timer                         5utp Verbose Scheduled timer job Variations Propagate Page Job Definition, id {238B8EEA-26A7-432F-8AC8-A4546426E739} at 30 Sep 2011 09:00:01 +0100 (now is 30 Sep 2011 08:59:01 +0100)  
    09/30/2011 08:59:01.90 OWSTIMER.EXE (0x2614)                   0x3228 Windows SharePoint Services   Timer                         5utp Verbose Scheduled timer job Scheduled Unpublish, id {163C063B-FFBF-40E6-94EF-97F97FC3059F} at 30 Sep 2011 09:00:01 +0100 (now is 30 Sep 2011 08:59:01 +0100)  
    09/30/2011 08:59:01.90 OWSTIMER.EXE (0x2614)                   0x3228 Windows SharePoint Services   Timer                         5utp Verbose Scheduled timer job Variations Propagate Page Job Definition, id {A48FDB99-C348-4DBC-99AD-2BFD49296E35} at 30 Sep 2011 09:00:01 +0100 (now is 30 Sep 2011 08:59:01 +0100)  
    09/30/2011 08:59:01.90 OWSTIMER.EXE (0x2614)                   0x3228 Windows SharePoint Services   Timer                         5utp Verbose Scheduled timer job Variations Propagate Page Job Definition, id {4BD426C4-7979-4EA7-9984-ABA89E5D748B} at 30 Sep 2011 09:00:01 +0100 (now is 30 Sep 2011 08:59:01 +0100)  
    09/30/2011 08:59:01.90 OWSTIMER.EXE (0x2614)                   0x3228 Windows SharePoint Services   Timer                         5utp Verbose Scheduled timer job Scheduled Approval, id {36E9E746-AE92-4A27-BB3C-412CBB6F52F3} at 30 Sep 2011 09:00:01 +0100 (now is 30 Sep 2011 08:59:01 +0100)  
    09/30/2011 08:59:04.69 w3wp.exe (0x1A64)                       0x34D0 Windows SharePoint Services   General                       8xfr Verbose PermissionMask check failed. asking for 0x00000001, have 0x00000000  
    09/30/2011 08:59:04.69 w3wp.exe (0x1A64)                       0x34D0 Windows SharePoint Services   General                       8xfr Verbose PermissionMask check failed. asking for 0x00000005, have 0x00000000  
    09/30/2011 08:59:04.69 w3wp.exe (0x1A64)                       0x34D0 Windows SharePoint Services   General                       8xfr Verbose PermissionMask check failed. asking for 0x00000015, have 0x00000000  
     
    09/30/2011 08:59:04.71 w3wp.exe (0x1A64)                       0x34D0 Windows SharePoint Services   General                       75g4 Verbose Usage: Rights and Roles: GetWebPartPage: Shared  
    09/30/2011 08:59:04.71 w3wp.exe (0x1A64)                       0x34D0 CMS                           Publishing                     7fz3 Medium   Setting [Display] as the FormContext.FormMode for the current page  
    09/30/2011 08:59:04.71 w3wp.exe (0x1A64)                       0x34D0 Windows SharePoint Services   Database                       880l Verbose ConnectionString: 'Data Source=RLGVLPINST2\MOSSOTHER;Initial Catalog=SharePoint_Config;Integrated Security=True;Enlist=False'    ConnectionState: Closed ConnectionTimeout: 15  
    09/30/2011 08:59:04.71 w3wp.exe (0x1A64)                       0x34D0                               1624                           8acb Verbose Reverting to process identity  
    09/30/2011 08:59:04.71 w3wp.exe (0x1A64)                       0x34D0                               1624                           8acc Verbose Current user before SqlConnection.Open: Name: <domain>\<accountname> SID: S-1-5-21-3303789354-2929308154-427975440-81829 ImpersonationLevel: None  
    09/30/2011 08:59:04.71 w3wp.exe (0x1A64)                       0x34D0                               1624                           8acf Verbose Current user after SqlConnection.Open: Name: <domain>\<accountname> SID: S-1-5-21-3303789354-2929308154-427975440-81829 ImpersonationLevel: None  
    09/30/2011 08:59:04.71 w3wp.exe (0x1A64)                       0x34D0 Windows SharePoint Services   Database                       880m Verbose SqlCommand: 'dbo.proc_getObject'     CommandType: StoredProcedure CommandTimeout: 0     Parameter: '@RETURN_VALUE' Type: Int Size: 0 Direction: ReturnValue Value: ''     Parameter: '@Id' Type: UniqueIdentifier Size: 0 Direction: Input Value: '8c0cd022-afa0-4f1b-9bbe-4f128c0cf4b3'  
    09/30/2011 08:59:04.72 w3wp.exe (0x1A64)                       0x34D0 Windows SharePoint Services   General                       8e1f High     Failed to find the XML file at location '12\Template\Features\CKSWikiDiscussionsList\Feature.xml'  
    09/30/2011 08:59:04.72 w3wp.exe (0x1A64)                       0x34D0 Windows SharePoint Services   Database                       880l Verbose ConnectionString: 'Data Source=RLGVLPINST2\MOSSOTHER;Initial Catalog=SharePoint_Config;Integrated Security=True;Enlist=False'    ConnectionState: Closed ConnectionTimeout: 15  
    09/30/2011 08:59:04.72 w3wp.exe (0x1A64)                       0x34D0                               1624                           8acb Verbose Reverting to process identity  
    09/30/2011 08:59:04.72 w3wp.exe (0x1A64)                       0x34D0                               1624                           8acc Verbose Current user before SqlConnection.Open: Name: <domain>\<accountname1>  SID: S-1-5-21-3303789354-2929308154-427975440-81829 ImpersonationLevel: None  
    09/30/2011 08:59:04.72 w3wp.exe (0x1A64)                       0x34D0                               1624                           8acf Verbose Current user after SqlConnection.Open: Name: <domain>\<accountname1> SID: S-1-5-21-3303789354-2929308154-427975440-81829 ImpersonationLevel: None  
    09/30/2011 08:59:04.72 w3wp.exe (0x1A64)                       0x34D0 Windows SharePoint Services   Database                       880m Verbose SqlCommand: 'dbo.proc_getObject'     CommandType: StoredProcedure CommandTimeout: 0     Parameter: '@RETURN_VALUE' Type: Int Size: 0 Direction: ReturnValue Value: ''     Parameter: '@Id' Type: UniqueIdentifier Size: 0 Direction: Input Value: '593ee132-558f-4bf9-82b9-f862fbd28e79'  
    09/30/2011 08:59:04.72 w3wp.exe (0x1A64)                       0x34D0 CMS                           Publishing                     7fz3 Medium   Setting [Display] as the FormContext.FormMode for the current page  
    09/30/2011 08:59:04.72 w3wp.exe (0x1A64)                       0x34D0 CMS                           Publishing                     7fz3 Medium   Setting [Display] as the FormContext.FormMode for the current page  
    09/30/2011 08:59:04.76 w3wp.exe (0x1A64)                       0x34D0 Windows SharePoint Services   Database                       880l Verbose ConnectionString: 'Data Source=RLGVLPINST2\MOSSOTHER;Initial Catalog=SharePoint_Config;Integrated Security=True;Enlist=False'    ConnectionState: Closed ConnectionTimeout: 15  
    09/30/2011 08:59:47.80 w3wp.exe (0x2238)                       0x246C                               1624                           8acc Verbose Current user before SqlConnection.Open: Name: <domain>\<accountname2> SID: S-1-5-21-3303789354-2929308154-427975440-81832 ImpersonationLevel: None  
    09/30/2011 08:59:47.82 w3wp.exe (0x2238)                       0x246C                               1624                           8acf Verbose Current user after SqlConnection.Open: Name: <domain>\<accountname2> SID: S-1-5-21-3303789354-2929308154-427975440-81832 ImpersonationLevel: None  
    09/30/2011 08:59:47.82 w3wp.exe (0x2238)                       0x246C Windows SharePoint Services   Database                       880m Verbose SqlCommand: 'dbo.proc_getObjectsByBaseClass'     CommandType: StoredProcedure CommandTimeout: 0     Parameter: '@RETURN_VALUE' Type: Int Size: 0 Direction: ReturnValue Value: ''     Parameter: '@BaseClassId' Type: UniqueIdentifier Size: 0 Direction: Input Value: '9920f486-2ff4-4d10-9532-e01979826585'     Parameter: '@ParentId' Type: UniqueIdentifier Size: 0 Direction: Input Value: '7c42be6b-abea-42f9-87bf-f708caea77ef'  
    09/30/2011 08:59:47.88 w3wp.exe (0x35A0)                       0x0EE0 Windows SharePoint Services   Database                       880l Verbose ConnectionString: 'Data Source=RLGVLPINST2\MOSSOTHER;Initial Catalog=SharePoint_Config;Integrated Security=True;Enlist=False'    ConnectionState: Closed ConnectionTimeout: 15  
    09/30/2011 08:59:47.88 w3wp.exe (0x35A0)                       0x0EE0                               1624                           8acb Verbose Reverting to process identity  
    09/30/2011 08:59:47.88 w3wp.exe (0x35A0)                       0x0EE0                               1624                           8acc Verbose Current user before SqlConnection.Open: Name: <domain>\<accountname3> SID: S-1-5-21-3303789354-2929308154-427975440-105202 ImpersonationLevel: None  
    09/30/2011 08:59:47.88 w3wp.exe (0x35A0)                       0x0EE0                               1624                           8acf Verbose Current user after SqlConnection.Open: Name: <domain>\<accountname4>  SID: S-1-5-21-3303789354-2929308154-427975440-105202 ImpersonationLevel: None  
    09/30/2011 08:59:47.88 w3wp.exe (0x35A0)                       0x0EE0 Windows SharePoint Services   Database                       880m Verbose SqlCommand: 'dbo.proc_getObjectsByBaseClass'     CommandType: StoredProcedure CommandTimeout: 0     Parameter: '@RETURN_VALUE' Type: Int Size: 0 Direction: ReturnValue Value: ''     Parameter: '@BaseClassId' Type: UniqueIdentifier Size: 0 Direction: Input Value: '9920f486-2ff4-4d10-9532-e01979826585'     Parameter: '@ParentId' Type: UniqueIdentifier Size: 0 Direction: Input Value: '7c42be6b-abea-42f9-87bf-f708caea77ef'  
    09/30/2011 08:59:47.93 w3wp.exe (0x132C)                       0x347C Windows SharePoint Services   Database                       880l Verbose ConnectionString: 'Data Source=RLGVLPINST2\MOSSOTHER;Initial Catalog=SharePoint_Config;Integrated Security=True;Enlist=False'    ConnectionState: Closed ConnectionTimeout: 15  
    09/30/2011 09:01:25.95 OWSTIMER.EXE (0x2614)                   0x1C08 Windows SharePoint Services   General                       0 Verbose Releasing SPRequest with allocation Id {260383B0-9F7E-4583-A0D6-00714714D566}  
    09/30/2011 09:01:25.96 OWSTIMER.EXE (0x2614)                   0x3320 Windows SharePoint Services   General                       0 Verbose Releasing SPRequest with allocation Id {7B14FA36-0C5F-4F4A-BC12-E52F80909C67}  
    09/30/2011 09:01:25.96 OWSTIMER.EXE (0x2614)                   0x1C08 Windows SharePoint Services   General                       0 Verbose Releasing SPRequest with allocation Id {50EE9236-6E7F-4B15-8923-7D49F9975BFA}  
    09/30/2011 09:01:25.96 OWSTIMER.EXE (0x2614)                   0x3320 Windows SharePoint Services   General                       0 Verbose Releasing SPRequest with allocation Id {3B2C4408-0148-401E-AACD-0061BD057C89}  
    Friday, September 30, 2011 11:15 AM