December 22, 2008 at 7:31 am
We've had problems recently where our SQL Server Agent service has stopped unexpectedly in both our 32-bit and 64-bit clusters. The following messages appear in the Event Viewer logs.
The description for Event ID ( 17052 ) in Source ( MSSQLSERVER ) cannot be found. The local computer may not have the necessary registry information or message DLL files to display messages from a remote computer. You may be able to use the /AUXSOURCE= flag to retrieve this description; see Help and Support for details. The following information is part of the event: SQLServerAgent Monitor: SQLServerAgent has terminated unexpectedly..
The description for Event ID ( 17052 ) in Source ( MSSQLSERVER ) cannot be found. The local computer may not have the necessary registry information or message DLL files to display messages from a remote computer. You may be able to use the /AUXSOURCE= flag to retrieve this description; see Help and Support for details. The following information is part of the event: SQLServerAgent Monitor failed to restart SQLServerAgent after SQLServerAgent terminated unexpectedly (reason: SQLSCMControl() returned error 5040, 'The cluster node already exists.')..
[sqagtres] CheckServiceAlive: Service is dead
[sqagtres] CheckServiceAlive: Service is dead
[sqagtres] CheckServiceAlive: Service is dead
SQLServerAgent service successfully started.
Any ideas?
Thanks, Dave
December 22, 2008 at 7:43 am
Dave - I'm sure you googled this but I found this link and wondered if this bore any resemblance to what you are seeing...
http://www.eggheadcafe.com/software/aspnet/31664499/sql2005-agent-failures-on.aspx
David
@SQLTentmaker“He is no fool who gives what he cannot keep to gain that which he cannot lose” - Jim Elliot
December 22, 2008 at 8:14 am
I did not see that one on Google. The only dependency for SQL Server Agent is MSSQLSERVER and that service is ok. We have Trend running on the server performing scheduled, off-hour, scans and have the normal exclusions configured within Trend so I don't think this is a virus problem. Unfortunately we are out of support calls for Microsoft until our 2009 agreement takes over, so we are on our own for now.
Thanks
December 22, 2008 at 2:18 pm
Anything suspicious in cluster log file?
MJ
December 22, 2008 at 2:35 pm
Nothing strange that I noticed, but I don't have a great deal of experience with clustering so perhaps I missed something. Here is a section of the log. Do you see anyting strange and is it normal to see so much activity such as enumerating drives?
000014b8.000014e0::2008/12/21-17:25:10.916 INFO [CP] CppRegNotifyThread checkpointing key Software\Microsoft\Microsoft SQL Server\MSSQL.1\MSSQLSERVER to id 4 due to timer
000014b8.000014e0::2008/12/21-17:25:10.916 INFO [Qfs] QfsGetTempFileName C:\DOCUME~1\mlb\LOCALS~1\Temp\, CLS, 14361 => C:\DOCUME~1\mlb\LOCALS~1\Temp\CLS3819.tmp, status 0
000014b8.000014e0::2008/12/21-17:25:10.916 INFO [Qfs] QfsDeleteFile C:\DOCUME~1\mlb\LOCALS~1\Temp\CLS3819.tmp, status 0
000014b8.000014e0::2008/12/21-17:25:10.947 INFO [Qfs] QfsRegSaveKey C:\DOCUME~1\mlb\LOCALS~1\Temp\CLS3819.tmp, status 0
000014b8.000014e0::2008/12/21-17:25:10.947 INFO [CP] CpSaveData: checkpointing data id 4 to quorum node 1
000014b8.000014e0::2008/12/21-17:25:10.947 INFO [CP] CppWriteCheckpoint checkpointing file C:\DOCUME~1\mlb\LOCALS~1\Temp\CLS3819.tmp to file Q:\MSCS\40b74c88-0544-42ec-8c10-c8966045d40d\00000004.CPT
000014b8.000014e0::2008/12/21-17:25:10.947 INFO [Qfs] QfsCreateDirectory Q:\MSCS\40b74c88-0544-42ec-8c10-c8966045d40d, status 183
000014b8.000014e0::2008/12/21-17:25:10.947 INFO [Qfs] QfsOpenFile C:\DOCUME~1\mlb\LOCALS~1\Temp\CLS3819.tmp => 3, 38cf960 status 0
000014b8.000014e0::2008/12/21-17:25:10.947 INFO [Qfs] QfsOpenFile Q:\MSCS\40b74c88-0544-42ec-8c10-c8966045d40d\00000004.CPT => 2, 38cf8d0 status 183
000014b8.000014e0::2008/12/21-17:25:10.947 INFO [Qfs] ReadFile 8a0 (regf) 32768 16384, (0=>0) 0 status 0
000014b8.000014e0::2008/12/21-17:25:10.947 INFO [Qfs] WriteFile 2b4 (regf) 16384, status 0 (0=>0)
000014b8.000014e0::2008/12/21-17:25:10.947 INFO [Qfs] ReadFile 8a0 (regf) 32768 0, (0=>0) 0 status 0
000014b8.000014e0::2008/12/21-17:25:10.947 INFO [Qfs] QfsFlushBuffers 2b4, status 0
000014b8.000014e0::2008/12/21-17:25:10.947 INFO [Qfs] QfsCloseHandle 8a0, status 0
000014b8.000014e0::2008/12/21-17:25:10.947 INFO [Qfs] QfsCloseHandle 2b4, status 0
000014b8.000014e0::2008/12/21-17:25:10.947 INFO [Qfs] QfsDeleteFile C:\DOCUME~1\mlb\LOCALS~1\Temp\CLS3819.tmp, status 0
00001584.00001590::2008/12/21-17:25:36.556 ERR SQL Server Agent : [sqagtres] CheckServiceAlive: Service is dead
00001584.00001590::2008/12/21-17:25:36.556 ERR SQL Server Agent : [sqagtres] CheckServiceAlive: Service is dead000014b8.00001598::2008/12/21-17:25:36.556 INFO [FM] NotifyCallBackRoutine: enqueuing event
000014b8.00001598::2008/12/21-17:25:36.556 INFO [FM] Calling RmNotifyChanges in monitor 1584.
000014b8.000014f0::2008/12/21-17:25:36.572 INFO [CP] CppResourceNotify for resource SQL Server Agent
000014b8.000014f4::2008/12/21-17:25:36.572 WARN [FM] FmpHandleResourceTransition: Resource Name = 8a76f4b2-e5ad-4a56-b49e-f880879da40d [SQL Server Agent] old state=2 new state=4
000014b8.000014f4::2008/12/21-17:25:36.572 INFO [GUM] GumSendUpdate: queuing updatetype 0 context 8
000014b8.000014f4::2008/12/21-17:25:36.572 INFO [GUM] GumSendUpdate: Dispatching seq 10932type 0 context 8 to node 1
000014b8.000014f4::2008/12/21-17:25:36.572 INFO [GUM] GumSendUpdate: completed update seq 10932type 0 context 8
000014b8.000014f4::2008/12/21-17:25:36.572 INFO [FM] FmpPropagateResourceState: resource 8a76f4b2-e5ad-4a56-b49e-f880879da40d failed event.
000014b8.000014f4::2008/12/21-17:25:36.572 INFO [FM] FmpHandleResourceFailure: taking resource 8a76f4b2-e5ad-4a56-b49e-f880879da40d and dependents offline
00001584.000023d8::2008/12/21-17:25:36.572 ERR SQL Server Agent : [sqagtres] CheckServiceAlive: Service is dead
000014b8.000014f4::2008/12/21-17:25:36.572 INFO [CP] CppResourceNotify for resource SQL Server Agent
000014b8.000014f4::2008/12/21-17:25:36.572 INFO [FM] RmTerminateResource: 8a76f4b2-e5ad-4a56-b49e-f880879da40d is now offline
000014b8.000014f4::2008/12/21-17:25:36.572 INFO [FM] RestartResourceTree, Restart resource 8a76f4b2-e5ad-4a56-b49e-f880879da40d
000014b8.000014f4::2008/12/21-17:25:36.572 INFO [FM] OnlineResource: 8a76f4b2-e5ad-4a56-b49e-f880879da40d depends on 40b74c88-0544-42ec-8c10-c8966045d40d. Bring online first.
000014b8.000014f4::2008/12/21-17:25:36.572 INFO [FM] FmpRmOnlineResource: bringing resource 8a76f4b2-e5ad-4a56-b49e-f880879da40d (resid 1823936) online.
000014b8.000014f4::2008/12/21-17:25:36.572 INFO [CP] CppResourceNotify for resource SQL Server Agent
000014b8.000014f4::2008/12/21-17:25:36.572 INFO [FM] FmpRmOnlineResource: called InterlockedIncrement on gdwQuoBlockingResources for resource 8a76f4b2-e5ad-4a56-b49e-f880879da40d
000014b8.000014f4::2008/12/21-17:25:36.572 INFO [GUM] GumSendUpdate: queuing updatetype 0 context 8
000014b8.000014f4::2008/12/21-17:25:36.572 INFO [GUM] GumSendUpdate: Dispatching seq 10933type 0 context 8 to node 1
000014b8.000014f4::2008/12/21-17:25:36.572 INFO [GUM] GumSendUpdate: completed update seq 10933type 0 context 8
000014b8.000014f4::2008/12/21-17:25:36.572 INFO [FM] FmpPropagateResourceState: resource 8a76f4b2-e5ad-4a56-b49e-f880879da40d pending event.
000014b8.000014f4::2008/12/21-17:25:36.572 INFO [FM] FmpRmOnlineResource: Resource 8a76f4b2-e5ad-4a56-b49e-f880879da40d pending
000014b8.000014f4::2008/12/21-17:25:36.572 INFO [FM] FmpRmOnlineResource: Returning. Resource 8a76f4b2-e5ad-4a56-b49e-f880879da40d, state 129, status 997.
000014b8.000014f4::2008/12/21-17:25:36.572 INFO [GUM] GumSendUpdate: queuing updatetype 0 context 11
000014b8.000014f4::2008/12/21-17:25:36.572 INFO [GUM] GumSendUpdate: Dispatching seq 10934type 0 context 11 to node 1
000014b8.000014f4::2008/12/21-17:25:36.572 INFO [GUM] GumSendUpdate: completed update seq 10934type 0 context 11
000014b8.000014f4::2008/12/21-17:25:36.572 INFO [GUM] GumSendUpdate: queuing updatetype 0 context 11
000014b8.000014f4::2008/12/21-17:25:36.572 INFO [GUM] GumSendUpdate: Dispatching seq 10935type 0 context 11 to node 1
000014b8.000014f4::2008/12/21-17:25:36.572 INFO [GUM] GumSendUpdate: completed update seq 10935type 0 context 11
000014b8.000014f4::2008/12/21-17:25:36.572 INFO [GUM] GumSendUpdate: queuing updatetype 0 context 11
000014b8.000014f4::2008/12/21-17:25:36.572 INFO [GUM] GumSendUpdate: Dispatching seq 10936type 0 context 11 to node 1
000014b8.000014f4::2008/12/21-17:25:36.572 INFO [GUM] GumSendUpdate: completed update seq 10936type 0 context 11
00001584.0000100c::2008/12/21-17:25:39.072 INFO [RM] RmpSetResourceStatus, Posting state 2 notification for resource
000014b8.00001598::2008/12/21-17:25:39.072 INFO [FM] NotifyCallBackRoutine: enqueuing event
000014b8.00001598::2008/12/21-17:25:39.072 INFO [FM] Calling RmNotifyChanges in monitor 1584.
000014b8.000014f0::2008/12/21-17:25:39.072 INFO [FM] FmpRmDoHandleCriticalResourceStateChange: call InterlockedDecrement on gdwQuoBlockingResources, Resource 8a76f4b2-e5ad-4a56-b49e-f880879da40d
000014b8.000014f4::2008/12/21-17:25:39.072 INFO [FM] FmpHandleResourceTransition: Resource Name = 8a76f4b2-e5ad-4a56-b49e-f880879da40d [SQL Server Agent] old state=129 new state=2
000014b8.000014f4::2008/12/21-17:25:39.072 INFO [GUM] GumSendUpdate: queuing updatetype 0 context 8
000014b8.000014f4::2008/12/21-17:25:39.072 INFO [GUM] GumSendUpdate: Dispatching seq 10937type 0 context 8 to node 1
000014b8.000014f4::2008/12/21-17:25:39.072 INFO [GUM] GumSendUpdate: completed update seq 10937type 0 context 8
000014b8.000014f4::2008/12/21-17:25:39.072 INFO [FM] FmpPropagateResourceState: resource 8a76f4b2-e5ad-4a56-b49e-f880879da40d online event.
000014b8.000014f4::2008/12/21-17:25:39.072 INFO [GUM] GumSendUpdate: queuing updatetype 0 context 11
000014b8.000014f4::2008/12/21-17:25:39.072 INFO [GUM] GumSendUpdate: Dispatching seq 10938type 0 context 11 to node 1
000014b8.000014f4::2008/12/21-17:25:39.072 INFO [GUM] GumSendUpdate: completed update seq 10938type 0 context 11
000014b8.000014f8::2008/12/21-17:25:45.947 INFO [Qfs] GetDiskFreeSpaceEx Q:\MSCS\, status 0
00001538.00001fac::2008/12/21-17:26:06.462 INFO Physical Disk : GetDiskInfoEx: enumerate disk PhysicalDrive1 only, not all disks.
00001538.000016fc::2008/12/21-17:26:06.478 INFO Physical Disk : GetDiskInfoEx: enumerate disk PhysicalDrive1 only, not all disks.
00001538.000016fc::2008/12/21-17:26:06.478 INFO Physical Disk : GetDiskInfoEx: enumerate disk PhysicalDrive2 only, not all disks.
00001538.000015fc::2008/12/21-17:26:06.493 INFO Physical Disk : GetDiskInfoEx: enumerate disk PhysicalDrive2 only, not all disks.
00001538.000015fc::2008/12/21-17:26:06.493 INFO Physical Disk : GetDiskInfoEx: enumerate disk PhysicalDrive4 only, not all disks.
00001538.00001fac::2008/12/21-17:26:06.509 INFO Physical Disk : GetDiskInfoEx: enumerate disk PhysicalDrive4 only, not all disks.
00001538.000015fc::2008/12/21-17:26:06.540 INFO Physical Disk : GetDiskInfoEx: enumerate disk PhysicalDrive5 only, not all disks.
00001538.00001fac::2008/12/21-17:26:06.556 INFO Physical Disk : GetDiskInfoEx: enumerate disk PhysicalDrive5 only, not all disks.
00001538.000015fc::2008/12/21-17:26:06.587 INFO Physical Disk : GetDiskInfoEx: enumerate disk PhysicalDrive3 only, not all disks.
00001538.00001fac::2008/12/21-17:26:06.587 INFO Physical Disk : GetDiskInfoEx: enumerate disk PhysicalDrive3 only, not all disks.
Viewing 5 posts - 1 through 4 (of 4 total)
You must be logged in to reply to this topic. Login to reply