w32tm debug help

Discussion in 'Windows Vista General Discussion' started by Mark Pfeifer, May 28, 2009.

  1. Mark Pfeifer

    Mark Pfeifer Guest

    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
    ....

    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
     
    Mark Pfeifer, May 28, 2009
    #1
    1. Advertisements

  2. Mark Pfeifer

    Mark Pfeifer Guest

    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

     
    Mark Pfeifer, May 28, 2009
    #2
    1. Advertisements

Ask a Question

Want to reply to this thread or ask your own question?

You'll need to choose a username for the site, which only take a couple of moments (here). After that, you can post your question and our members will help you out.