M
Mark Pfeifer
Repost for managed support...
I am running Vista 32-bit with SP2 and I am unable to sync the time via the
w32tm service. I just get an error on the screen with no entries in the
system event viewer. Listed below is the debug of w32tm /resync
/rediscover ... and the UI attempt to update.
Can anyone shed any light on this? Other ntp clients work fine and I am
able to do a stripchart command against time.windows.com w/o an issue. So,
I don't think it is a firewall issue.
Any ideas? TIA
Mark
****************************************
> 149166 22:37:38.4257189s - ---------- Log File Opened -----------------
> 149166 22:37:50.9993189s - RPC Caller is
> (S-1-5-21-1037478806-2609463539-4057573387-1000)
> 149166 22:37:50.9993189s - RPC Call Attribute is local=1, kernel=0,
> session=0, authentication=6, protocol=2, OpNum=0
> 149166 22:37:50.9993189s - RPC Call - Rediscover
> 149166 22:37:50.9993189s - W32TmServiceMain: Network Topology Change (RPC)
> 149166 22:37:50.9993189s - TimeProvCommand([NtpClient], TPC_NetTopoChange)
> called.
> 149166 22:37:50.9993189s - NtpProvider: Network Topology Change
> 149166 22:37:50.9993189s - Peers reset: p-p:1 a-p:1 a-x:0
> 149166 22:37:50.9993189s - NtpProvider: Created 0 sockets.
> 149166 22:37:50.9993189s - StartListeningThread completed!
> 149166 22:37:50.9993189s - PeerPollingThread: waiting forever
> 149166 22:37:50.9993189s - StartPeerPollingThread completed!
> 149166 22:37:50.9993189s - W32TmServiceMain: waiting 983.204s
> 149166 22:37:50.9993189s - W32TmServiceMain: ********** Time Slip
> Notification **********
> 149166 22:37:50.9993189s - PeerPollingThread: PeerListUpdated
> 149166 22:37:50.9993189s - PeerPollingThread: waiting forever
> 149166 22:37:50.9993189s - ClockDispln TimeSlip:TimeSlip LastUTC:70046
> SetUnsync: LI:3 S:0 RDl:0 RDs:0 TSF:0x0
> 149166 22:37:50.9993189s - TimeProvCommand([NtpClient], TPC_TimeJumped)
> called.
> 149166 22:37:50.9993189s - W32TmServiceMain: waiting i16.000s (1024.000s)
> 149166 22:37:50.9993189s - PeerPollingThread: PeerListUpdated
> 149166 22:37:50.9993189s - PeerPollingThread: waiting forever
> 149166 22:38:07.0049189s - W32TmServiceMain: timeout
> 149166 22:38:07.0049189s - W32TmServiceMain: waiting 1024.000s
BTW - Here is the log after the UI attempts to update
149166 22:42:17.2065189s - RPC Caller is
(S-1-5-21-1037478806-2609463539-4057573387-1000)
149166 22:42:17.2065189s - RPC Call Attribute is local=1, kernel=0,
session=0, authentication=6, protocol=2, OpNum=0
149166 22:42:17.2065189s - RPC Call - HardResync
149166 22:42:17.2065189s - W32TmServiceMain: ********** Time Slip
Notification **********
149166 22:42:17.2065189s - ClockDispln TimeSlip:TimeSlip LastUTC:87111
SetUnsync: LI:3 S:0 RDl:0 RDs:0 TSF:0x0
149166 22:42:17.2065189s - TimeProvCommand([NtpClient], TPC_TimeJumped)
called.
149166 22:42:17.2065189s - W32TmServiceMain: waiting i16.000s (1024.000s)
149166 22:42:17.2065189s - PeerPollingThread: PeerListUpdated
149166 22:42:17.2065189s - PeerPollingThread: waiting forever
149166 22:42:33.2121189s - W32TmServiceMain: timeout
149166 22:42:33.2121189s - W32TmServiceMain: waiting 1024.000s
149166 22:42:33.2121189s - RPC Call - Query provider status
149166 22:42:33.2121189s - TimeProvCommand([NtpClient], TPC_Query) called.
149166 22:42:33.2121189s - RPC Caller is
(S-1-5-21-1037478806-2609463539-4057573387-1000)
149166 22:42:33.2121189s - RPC Call Attribute is local=1, kernel=0,
session=0, authentication=6, protocol=2, OpNum=6
149166 22:42:33.2121189s - RPC Call - Query Status
149166 22:42:33.2121189s - W32TimeHandler called:
SERVICE_CONTROL_PARAMCHANGE
149166 22:42:33.2121189s - W32TmServiceMain: Param change notification
149166 22:42:33.2121189s - ReadConfig: Found provider 'NtpClient':
149166 22:42:33.2121189s - ReadConfig: 'Enabled'=0x00000001
149166 22:42:33.2121189s - ReadConfig:
'DllName'='C:\Windows\system32\w32time.dll'
149166 22:42:33.2121189s - ReadConfig:
'DllName'='C:\Windows\system32\w32time.dll'
149166 22:42:33.2121189s - ReadConfig:
'DllName'='C:\Windows\system32\w32time.dll'
149166 22:42:33.2121189s - ReadConfig: 'InputProvider'=0x00000001
149166 22:42:33.2121189s - ReadConfig: Found provider 'NtpServer':
149166 22:42:33.2121189s - ReadConfig: 'Enabled'=0x00000000
149166 22:42:33.2121189s - ReadConfig:
'DllName'='C:\Windows\system32\w32time.dll'
149166 22:42:33.2121189s - ReadConfig:
'DllName'='C:\Windows\system32\w32time.dll'
149166 22:42:33.2121189s - ReadConfig:
'DllName'='C:\Windows\system32\w32time.dll'
149166 22:42:33.2121189s - ReadConfig: 'InputProvider'=0x00000000
149166 22:42:33.2121189s - ReadConfig: 'PhaseCorrectRate'=0x00000001 (2)
149166 22:42:33.2121189s - ReadConfig: 'UpdateInterval'=0x00057E40 (2)
149166 22:42:33.2121189s - ReadConfig: 'FrequencyCorrectRate'=0x00000004 (2)
149166 22:42:33.2121189s - ReadConfig: 'PollAdjustFactor'=0x00000005 (2)
149166 22:42:33.2121189s - ReadConfig: 'LargePhaseOffset'=0x02FAF080 (2)
149166 22:42:33.2121189s - ReadConfig: 'SpikeWatchPeriod'=0x00000384 (2)
149166 22:42:33.2121189s - ReadConfig: 'HoldPeriod'=0x00000005 (2)
149166 22:42:33.2121189s - ReadConfig: 'MinPollInterval'=0x0000000A (2)
149166 22:42:33.2121189s - ReadConfig: 'MaxPollInterval'=0x0000000F (2)
149166 22:42:33.2121189s - ReadConfig: 'AnnounceFlags'=0x0000000A (2)
149166 22:42:33.2121189s - ReadConfig: 'LocalClockDispersion'=0x0000000A (2)
149166 22:42:33.2121189s - ReadConfig: 'MaxNegPhaseCorrection'=0x0000D2F0
(2)
149166 22:42:33.2121189s - ReadConfig: 'MaxPosPhaseCorrection'=0x0000D2F0
(2)
149166 22:42:33.2121189s - ReadConfig: 'EventLogFlags'=0x00000002 (2)
149166 22:42:33.2121189s - ReadConfig: 'MaxAllowedPhaseOffset'=0x00000001
(2)
149166 22:42:33.2121189s - ReadConfig: 'TimeJumpAuditOffset'=0x00007080 (2)
149166 22:42:33.2121189s - No params changed for local clock.
149166 22:42:33.2121189s - /--TimeProvider: new enabled provider list
149166 22:42:33.2121189s - | Name:NtpClient, Started:0, Input:1, Marked:0,
RefCount:0, DllName:C:\Windows\system32\w32time.dll
149166 22:42:33.2121189s - >--
149166 22:42:33.2121189s - | Total:1, Input: 1, Marked: 0
149166 22:42:33.2121189s - \--
149166 22:42:33.2121189s - /--TimeProvider: Parameter change before list is
updated
149166 22:42:33.2121189s - | Name:NtpClient, Started:1, Input:1, Marked:0,
RefCount:0, DllName:C:\Windows\system32\w32time.dll
149166 22:42:33.2121189s - >--
149166 22:42:33.2121189s - | Total:1, Input: 1, Marked: 0
149166 22:42:33.2121189s - \--
149166 22:42:33.2121189s - /--TimeProvider: new disabled provider list
149166 22:42:33.2121189s - | Name:NtpServer, Started:0, Input:0, Marked:0,
RefCount:0, DllName:C:\Windows\system32\w32time.dll
149166 22:42:33.2121189s - >--
149166 22:42:33.2121189s - | Total:1, Input: 0, Marked: 0
149166 22:42:33.2121189s - \--
149166 22:42:33.2121189s - /--TimeProvider: Parameter change before list is
updated
149166 22:42:33.2121189s - | Name:NtpServer, Started:0, Input:0, Marked:0,
RefCount:0, DllName:C:\Windows\system32\w32time.dll
149166 22:42:33.2121189s - >--
149166 22:42:33.2121189s - | Total:1, Input: 0, Marked: 0
149166 22:42:33.2121189s - \--
149166 22:42:33.2121189s - TimeProvCommand([NtpClient], TPC_UpdateConfig)
called.
149166 22:42:33.2121189s - ReadConfig:
'AllowNonstandardModeCombinations'=0x00000001 (2)
149166 22:42:33.2121189s - ReadConfig: 'CompatibilityFlags'=0x80000000 (2)
149166 22:42:33.2121189s - ReadConfig: 'SpecialPollInterval'=0x00093A80 (2)
149166 22:42:33.2121189s - ReadConfig:
'ResolvePeerBackoffMinutes'=0x0000000F (2)
149166 22:42:33.2121189s - ReadConfig:
'ResolvePeerBackoffMaxTimes'=0x00000007 (2)
149166 22:42:33.2121189s - ReadConfig: 'EventLogFlags'=0x00000001 (2)
149166 22:42:33.2121189s - ReadConfig: 'LargeSampleSkew'=0x00000003 (2)
149166 22:42:33.2121189s - ReadConfig: 'Type'=NTP (2)
149166 22:42:33.2121189s - ReadConfig: 'NtpServer'=time.windows.com,0x9 (2)
149166 22:42:33.2121189s - ReadConfig:
'ManualPeerList'(parsed)='time.windows.com,0x9'
149166 22:42:33.2121189s - ManualPeerListUpdate: add:0 del:0 noch:1
149166 22:42:33.2121189s - StartListeningThread completed!
149166 22:42:33.2121189s - PeerPollingThread: waiting forever
149166 22:42:33.2121189s - StartPeerPollingThread completed!
149166 22:42:33.2121189s - Provider list: 0 stopped, 0 started, 1 not
changed.
149166 22:42:33.2121189s - /--TimeProvider: Parameter change after list is
updated
149166 22:42:33.2121189s - | Name:NtpClient, Started:1, Input:1, Marked:0,
RefCount:0, DllName:C:\Windows\system32\w32time.dll
149166 22:42:33.2121189s - >--
149166 22:42:33.2121189s - | Total:1, Input: 1, Marked: 0
149166 22:42:33.2121189s - \--
149166 22:42:33.2121189s - W32TmServiceMain: waiting 1024.000s
I am running Vista 32-bit with SP2 and I am unable to sync the time via the
w32tm service. I just get an error on the screen with no entries in the
system event viewer. Listed below is the debug of w32tm /resync
/rediscover ... and the UI attempt to update.
Can anyone shed any light on this? Other ntp clients work fine and I am
able to do a stripchart command against time.windows.com w/o an issue. So,
I don't think it is a firewall issue.
Any ideas? TIA
Mark
****************************************
> 149166 22:37:38.4257189s - ---------- Log File Opened -----------------
> 149166 22:37:50.9993189s - RPC Caller is
> (S-1-5-21-1037478806-2609463539-4057573387-1000)
> 149166 22:37:50.9993189s - RPC Call Attribute is local=1, kernel=0,
> session=0, authentication=6, protocol=2, OpNum=0
> 149166 22:37:50.9993189s - RPC Call - Rediscover
> 149166 22:37:50.9993189s - W32TmServiceMain: Network Topology Change (RPC)
> 149166 22:37:50.9993189s - TimeProvCommand([NtpClient], TPC_NetTopoChange)
> called.
> 149166 22:37:50.9993189s - NtpProvider: Network Topology Change
> 149166 22:37:50.9993189s - Peers reset: p-p:1 a-p:1 a-x:0
> 149166 22:37:50.9993189s - NtpProvider: Created 0 sockets.
> 149166 22:37:50.9993189s - StartListeningThread completed!
> 149166 22:37:50.9993189s - PeerPollingThread: waiting forever
> 149166 22:37:50.9993189s - StartPeerPollingThread completed!
> 149166 22:37:50.9993189s - W32TmServiceMain: waiting 983.204s
> 149166 22:37:50.9993189s - W32TmServiceMain: ********** Time Slip
> Notification **********
> 149166 22:37:50.9993189s - PeerPollingThread: PeerListUpdated
> 149166 22:37:50.9993189s - PeerPollingThread: waiting forever
> 149166 22:37:50.9993189s - ClockDispln TimeSlip:TimeSlip LastUTC:70046
> SetUnsync: LI:3 S:0 RDl:0 RDs:0 TSF:0x0
> 149166 22:37:50.9993189s - TimeProvCommand([NtpClient], TPC_TimeJumped)
> called.
> 149166 22:37:50.9993189s - W32TmServiceMain: waiting i16.000s (1024.000s)
> 149166 22:37:50.9993189s - PeerPollingThread: PeerListUpdated
> 149166 22:37:50.9993189s - PeerPollingThread: waiting forever
> 149166 22:38:07.0049189s - W32TmServiceMain: timeout
> 149166 22:38:07.0049189s - W32TmServiceMain: waiting 1024.000s
BTW - Here is the log after the UI attempts to update
149166 22:42:17.2065189s - RPC Caller is
(S-1-5-21-1037478806-2609463539-4057573387-1000)
149166 22:42:17.2065189s - RPC Call Attribute is local=1, kernel=0,
session=0, authentication=6, protocol=2, OpNum=0
149166 22:42:17.2065189s - RPC Call - HardResync
149166 22:42:17.2065189s - W32TmServiceMain: ********** Time Slip
Notification **********
149166 22:42:17.2065189s - ClockDispln TimeSlip:TimeSlip LastUTC:87111
SetUnsync: LI:3 S:0 RDl:0 RDs:0 TSF:0x0
149166 22:42:17.2065189s - TimeProvCommand([NtpClient], TPC_TimeJumped)
called.
149166 22:42:17.2065189s - W32TmServiceMain: waiting i16.000s (1024.000s)
149166 22:42:17.2065189s - PeerPollingThread: PeerListUpdated
149166 22:42:17.2065189s - PeerPollingThread: waiting forever
149166 22:42:33.2121189s - W32TmServiceMain: timeout
149166 22:42:33.2121189s - W32TmServiceMain: waiting 1024.000s
149166 22:42:33.2121189s - RPC Call - Query provider status
149166 22:42:33.2121189s - TimeProvCommand([NtpClient], TPC_Query) called.
149166 22:42:33.2121189s - RPC Caller is
(S-1-5-21-1037478806-2609463539-4057573387-1000)
149166 22:42:33.2121189s - RPC Call Attribute is local=1, kernel=0,
session=0, authentication=6, protocol=2, OpNum=6
149166 22:42:33.2121189s - RPC Call - Query Status
149166 22:42:33.2121189s - W32TimeHandler called:
SERVICE_CONTROL_PARAMCHANGE
149166 22:42:33.2121189s - W32TmServiceMain: Param change notification
149166 22:42:33.2121189s - ReadConfig: Found provider 'NtpClient':
149166 22:42:33.2121189s - ReadConfig: 'Enabled'=0x00000001
149166 22:42:33.2121189s - ReadConfig:
'DllName'='C:\Windows\system32\w32time.dll'
149166 22:42:33.2121189s - ReadConfig:
'DllName'='C:\Windows\system32\w32time.dll'
149166 22:42:33.2121189s - ReadConfig:
'DllName'='C:\Windows\system32\w32time.dll'
149166 22:42:33.2121189s - ReadConfig: 'InputProvider'=0x00000001
149166 22:42:33.2121189s - ReadConfig: Found provider 'NtpServer':
149166 22:42:33.2121189s - ReadConfig: 'Enabled'=0x00000000
149166 22:42:33.2121189s - ReadConfig:
'DllName'='C:\Windows\system32\w32time.dll'
149166 22:42:33.2121189s - ReadConfig:
'DllName'='C:\Windows\system32\w32time.dll'
149166 22:42:33.2121189s - ReadConfig:
'DllName'='C:\Windows\system32\w32time.dll'
149166 22:42:33.2121189s - ReadConfig: 'InputProvider'=0x00000000
149166 22:42:33.2121189s - ReadConfig: 'PhaseCorrectRate'=0x00000001 (2)
149166 22:42:33.2121189s - ReadConfig: 'UpdateInterval'=0x00057E40 (2)
149166 22:42:33.2121189s - ReadConfig: 'FrequencyCorrectRate'=0x00000004 (2)
149166 22:42:33.2121189s - ReadConfig: 'PollAdjustFactor'=0x00000005 (2)
149166 22:42:33.2121189s - ReadConfig: 'LargePhaseOffset'=0x02FAF080 (2)
149166 22:42:33.2121189s - ReadConfig: 'SpikeWatchPeriod'=0x00000384 (2)
149166 22:42:33.2121189s - ReadConfig: 'HoldPeriod'=0x00000005 (2)
149166 22:42:33.2121189s - ReadConfig: 'MinPollInterval'=0x0000000A (2)
149166 22:42:33.2121189s - ReadConfig: 'MaxPollInterval'=0x0000000F (2)
149166 22:42:33.2121189s - ReadConfig: 'AnnounceFlags'=0x0000000A (2)
149166 22:42:33.2121189s - ReadConfig: 'LocalClockDispersion'=0x0000000A (2)
149166 22:42:33.2121189s - ReadConfig: 'MaxNegPhaseCorrection'=0x0000D2F0
(2)
149166 22:42:33.2121189s - ReadConfig: 'MaxPosPhaseCorrection'=0x0000D2F0
(2)
149166 22:42:33.2121189s - ReadConfig: 'EventLogFlags'=0x00000002 (2)
149166 22:42:33.2121189s - ReadConfig: 'MaxAllowedPhaseOffset'=0x00000001
(2)
149166 22:42:33.2121189s - ReadConfig: 'TimeJumpAuditOffset'=0x00007080 (2)
149166 22:42:33.2121189s - No params changed for local clock.
149166 22:42:33.2121189s - /--TimeProvider: new enabled provider list
149166 22:42:33.2121189s - | Name:NtpClient, Started:0, Input:1, Marked:0,
RefCount:0, DllName:C:\Windows\system32\w32time.dll
149166 22:42:33.2121189s - >--
149166 22:42:33.2121189s - | Total:1, Input: 1, Marked: 0
149166 22:42:33.2121189s - \--
149166 22:42:33.2121189s - /--TimeProvider: Parameter change before list is
updated
149166 22:42:33.2121189s - | Name:NtpClient, Started:1, Input:1, Marked:0,
RefCount:0, DllName:C:\Windows\system32\w32time.dll
149166 22:42:33.2121189s - >--
149166 22:42:33.2121189s - | Total:1, Input: 1, Marked: 0
149166 22:42:33.2121189s - \--
149166 22:42:33.2121189s - /--TimeProvider: new disabled provider list
149166 22:42:33.2121189s - | Name:NtpServer, Started:0, Input:0, Marked:0,
RefCount:0, DllName:C:\Windows\system32\w32time.dll
149166 22:42:33.2121189s - >--
149166 22:42:33.2121189s - | Total:1, Input: 0, Marked: 0
149166 22:42:33.2121189s - \--
149166 22:42:33.2121189s - /--TimeProvider: Parameter change before list is
updated
149166 22:42:33.2121189s - | Name:NtpServer, Started:0, Input:0, Marked:0,
RefCount:0, DllName:C:\Windows\system32\w32time.dll
149166 22:42:33.2121189s - >--
149166 22:42:33.2121189s - | Total:1, Input: 0, Marked: 0
149166 22:42:33.2121189s - \--
149166 22:42:33.2121189s - TimeProvCommand([NtpClient], TPC_UpdateConfig)
called.
149166 22:42:33.2121189s - ReadConfig:
'AllowNonstandardModeCombinations'=0x00000001 (2)
149166 22:42:33.2121189s - ReadConfig: 'CompatibilityFlags'=0x80000000 (2)
149166 22:42:33.2121189s - ReadConfig: 'SpecialPollInterval'=0x00093A80 (2)
149166 22:42:33.2121189s - ReadConfig:
'ResolvePeerBackoffMinutes'=0x0000000F (2)
149166 22:42:33.2121189s - ReadConfig:
'ResolvePeerBackoffMaxTimes'=0x00000007 (2)
149166 22:42:33.2121189s - ReadConfig: 'EventLogFlags'=0x00000001 (2)
149166 22:42:33.2121189s - ReadConfig: 'LargeSampleSkew'=0x00000003 (2)
149166 22:42:33.2121189s - ReadConfig: 'Type'=NTP (2)
149166 22:42:33.2121189s - ReadConfig: 'NtpServer'=time.windows.com,0x9 (2)
149166 22:42:33.2121189s - ReadConfig:
'ManualPeerList'(parsed)='time.windows.com,0x9'
149166 22:42:33.2121189s - ManualPeerListUpdate: add:0 del:0 noch:1
149166 22:42:33.2121189s - StartListeningThread completed!
149166 22:42:33.2121189s - PeerPollingThread: waiting forever
149166 22:42:33.2121189s - StartPeerPollingThread completed!
149166 22:42:33.2121189s - Provider list: 0 stopped, 0 started, 1 not
changed.
149166 22:42:33.2121189s - /--TimeProvider: Parameter change after list is
updated
149166 22:42:33.2121189s - | Name:NtpClient, Started:1, Input:1, Marked:0,
RefCount:0, DllName:C:\Windows\system32\w32time.dll
149166 22:42:33.2121189s - >--
149166 22:42:33.2121189s - | Total:1, Input: 1, Marked: 0
149166 22:42:33.2121189s - \--
149166 22:42:33.2121189s - W32TmServiceMain: waiting 1024.000s