NTP bug in Windows 2003 ??

Hello,
we have upgraded our domain from Windows NT to Windows 2003 a few weeks ago.

Since then we are unable to sync our domain time with an NTP server on a
Unix system.

The same NTP server has been succesfully contacted by our NT DC before
and still can be used by a Windows 2000 member server (see Windows
2000.log).

The ntp client on the Windows 2003 Server somehow does not like the NTP
data (see Windows 2003.log). We have tried serveral NTP servers, but our
Windows 2003 server does not like any of them.

I would very much appreciate any hint what its problem could be or what
has been changed in the w32time service from Windows 2000 to Windows 2003.

Regards,
Stefan







W32Time: BEGIN:InitAdjIncr
W32Time: Adj 156250 , Incr 156250 fAdjust 0
W32Time: END:Line 2503
W32Time: BEGIN:TsUpTheThread
W32Time: END Line 1407
W32Time: TimeMMInit()
W32Time: Kernel timer : using default maximum resolution
W32Time: MaximumTime = 156250
W32Time: CurrentTime = 156250
W32Time: Timer calibrated, looped 1 times
W32Time: BEGIN:InitTmCfg
W32Time: END:Line 807
W32Time: BEGIN:InitTmCli
W32Time: END:Line 2596
W32Time: BEGIN:InitTmData
W32Time: END:Line 2618
W32Time: AvoidTimeSyncOnWan 0
W32Time: ntpserver - rumtime4.uni-mannheim.de
W32Time: BEGIN:CMOSSynchSet
W32Time: Setting adjustment 156250 - Bool 0
W32Time: BEGIN:SetTSTimeRes
W32Time: END:Line 1295
W32Time: END:Line 864
W32Time: BEGIN:InitializeDC
W32Time: BEGIN:GetRole
W32Time: Role is 'workstation'
W32Time: END Line 672
W32Time: END:Line 704
W32Time: timeBeginPeriod: setting resolution 9
W32Time: BEGIN:TimeSync
W32Time: BEGIN:FGetType
W32Time: END Line 254
W32Time: BEGIN:FDoTimeNTPType
W32Time: BEGIN:ChooseNTPServer
W32Time: END Line 2178
W32Time: BEGIN:GetSocketForSynch
W32Time: NTP: ntpptrs[0] - RUMTIME4.UNI-MANNHEIM.DE
W32Time: rgbNTPServer RUMTIME4.UNI-MANNHEIM.DE
W32Time: Port Pinging to - 123
W32Time: Connecting to "RUMTIME4.UNI-MANNHEIM.DE" (134.155.51.118)
W32Time: END:Line 1170
W32Time: BEGIN:GetDefaultRid
W32Time: END Line 2359
W32Time: BEGIN:ComputeDelay
W32Time: BEGIN:NTPTry -- init
W32Time: END Line 1683
W32Time: BEGIN:NTPTry -- try
W32Time: BEGIN:ComputeInterval
W32Time: END Line 2479
W32Time: Sending to server 48 bytes...
W32Time: Recv'ed from server 48 Bytes...
W32Time: END Line 1885
W32Time: BEGIN:NTPTry -- delay
W32Time: END Line 2012
W32Time: Round trip was 0ms
W32Time: BEGIN:NTPTry -- gettime
W32Time: BEGIN:Fgmtimetonttime
W32Time: END Line 2563
W32Time: END Line 1998
W32Time: one-way delay is 0ms
W32Time: END Line 1645
W32Time: END Line 368
W32Time: BEGIN:TimeDiff
W32Time: ClockError -1209
W32Time: END Line 2542
W32Time: BEGIN:FCheckTimeSanity
W32Time: Adjusting time by 1209 ms. No eventlog messages since time difference is 0 <1 minute
W32Time: END Line 570
W32Time: BEGIN:SetTimeNow
W32Time: Skewing for backwards, badj, btime = 78125 2418
W32Time: END Line 1280
W32Time: Time was 00min 22.718s
W32Time: Time is 00min 21.509s
W32Time: Error 1209ms
W32Time: BEGIN:CheckLeapFlag
W32Time: END:Line 606
W32Time: BEGIN:ComputePostTimeData
W32Time: BEGIN:ComputeInterval
W32Time: END Line 2479
W32Time: BEGIN:ComputeSleepStuff
W32Time: Computed stagger is 0ms, bias is 0ms
W32Time: Time until next sync - 2699.960s
W32Time: END:Line 816
W32Time: END:Line 221
W32Time: END:Line 196
W32Time: BEGIN:TermTime
W32Time: TimeMMCleanup()
W32Time: BEGIN:FinishCleanup
W32Time: BEGIN:TsUpTheThread
W32Time: END Line 1407
W32Time: Time service stopped.
W32Time: END:Line 407


147146 16:11:46.4375000s - ---------- Log File Opened -----------------
147146 16:11:46.4375000s - Entered W32TmServiceMain
147146 16:11:46.4375000s - CurSpc:15625000ns BaseSpc:15625000ns SyncToCmos:Yes
147146 16:11:46.4375000s - PerfFreq:3579545c/s
147146 16:11:46.4375000s - Time zone OK.
147146 16:11:46.4375000s - DomainHierarchy: LSA role change notification. Redetecting.
147146 16:11:46.4375000s - DomainHierarchy: we are now the domain root. Should be advertised as reliable
147146 16:11:46.4375000s - ClockDisciplineThread: Starting:147146 16:11:46.4375000s - LI:0 S:1 RDl:0 RDs:100000000 TSF:0x0
147146 16:11:46.4375000s - ClockDispln: we're a reliable time service with no time source: LS: 0, TN: 864000000000, WAIT: 86400000
147146 16:11:46.4375000s - Starting Providers.
147146 16:11:46.4375000s - Starting 'NtpClient', dll:'F:\WINDOWS\system32\w32time.dll'
147146 16:11:46.4375000s - NtpTimeProvOpen("NtpClient") called.
147146 16:11:46.4375000s - NtpProvider: Created 2 sockets (1 listen-only): 134.155.55.138:123, (127.0.0.1:123)
147146 16:11:46.4375000s - NtpClient started.
147146 16:11:46.4375000s - Starting 'NtpServer', dll:'F:\WINDOWS\system32\w32time.dll'
147146 16:11:46.4375000s - NtpTimeProvOpen("NtpServer") called.
147146 16:11:46.4375000s - PeerPollingThread: PeerListUpdated
147146 16:11:46.4375000s - Resolving rumtime4.uni-mannheim.de
147146 16:11:46.4375000s - NtpServer started.
147146 16:11:46.4375000s - Successfully started 2 providers.
147146 16:11:46.4375000s - W32TmServiceMain: waiting i16.000s (64.000s)
147146 16:11:46.4375000s - PeerPollingThread: PeerListUpdated
147146 16:11:46.4375000s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 16:11:46.4375000s - W32TmServiceMain: waiting i16.000s (64.000s)
147146 16:11:48.1875000s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +425.4126690s
147146 16:11:48.1875000s - W32TmServiceMain: resync req, irreg already pending.
147146 16:11:48.1875000s - W32TmServiceMain: waiting i14.250s (62.250s)
147146 16:11:48.4375000s - W32TimeHandler called: SERVICE_CONTROL_INTERROGATE
147146 16:12:02.4375000s - W32TmServiceMain: timeout
147146 16:12:02.4375000s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: SO:4254126690 KPhO:0 *PhO:4254126690 uT:112 SD:38995981 LI:0 S:3 RDl:118412 RDs:4332118653 TSF:0x0 Unset->Hold
147146 16:19:07.8501690s - Logging information: The time service is now synchronizing the system time with the time source rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123).
147146 16:19:07.8501690s - TimeProvCommand([NtpClient], TPC_TimeJumped) called.
147146 16:19:07.8501690s - TimeProvCommand([NtpServer], TPC_TimeJumped) called.
147146 16:19:07.8501690s - W32TmServiceMain: waiting 64.000s
147146 16:19:07.8501690s - PeerPollingThread: PeerListUpdated
147146 16:19:07.8501690s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 16:19:33.0220440s - Computing server digest: OLD:FALSE, RID:00001976
147146 16:19:33.0689190s - TransmitResponse: sent 134.155.55.138:123->134.155.4.35:123
147146 16:10:53.8906250s - Ignoring packet that failed tests from rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123).
147146 16:10:57.5312500s - RPC Caller is VWLPOOL\stefan (S-1-5-21-77879606-1260280510-1874078741-2586)
147146 16:10:57.5312500s - RPC Call - HardResync
147146 16:10:57.5312500s - W32TmServiceMain: ********** Time Slip Notification **********
147146 16:10:57.5312500s - ClockDispln TimeSlip:147146 16:10:57.5312500s - LI:0 S:3 RDl:0 RDs:100000000 TSF:0x0
147146 16:10:57.5310000s - ClockDispln: we're a reliable time service with no time source: LS: 0, TN: 864000000000, WAIT: 86400000
147146 16:10:57.5310000s - TimeProvCommand([NtpClient], TPC_TimeJumped) called.
147146 16:10:57.5310000s - TimeProvCommand([NtpServer], TPC_TimeJumped) called.
147146 16:10:57.5310000s - W32TmServiceMain: waiting i16.000s (64.000s)
147146 16:10:57.5466250s - PeerPollingThread: PeerListUpdated
147146 16:10:57.5466250s - PeerPollingThread: PeerListUpdated
147146 16:10:59.0310000s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 16:11:13.5622500s - W32TmServiceMain: timeout
147146 16:11:13.5622500s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
147146 16:11:13.5622500s - W32TmServiceMain: waiting 64.000s
147146 16:11:33.3747500s - Computing server digest: OLD:FALSE, RID:00001976
147146 16:11:33.4372500s - TransmitResponse: sent 134.155.55.138:123->134.155.4.35:123
147146 16:11:55.9528750s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +543.6536720s
147146 16:11:55.9528750s - W32TmServiceMain: resync req, irreg now pending.
147146 16:11:55.9528750s - W32TmServiceMain: waiting i0.000s (21.609s)
147146 16:11:55.9685000s - W32TmServiceMain: timeout
147146 16:11:55.9685000s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: SO:5436536720 KPhO:0 *PhO:5436536720 uT:3739 SD:55135271 LI:0 S:3 RDl:6199 RDs:5514490849 TSF:0x0 Unset->Hold
147146 16:20:59.6221720s - TimeProvCommand([NtpClient], TPC_TimeJumped) called.
147146 16:20:59.6221720s - TimeProvCommand([NtpServer], TPC_TimeJumped) called.
147146 16:20:59.6221720s - W32TmServiceMain: waiting 64.000s
147146 16:20:59.6221720s - PeerPollingThread: PeerListUpdated
147146 16:20:59.6221720s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 16:21:41.0440470s - Computing server digest: OLD:FALSE, RID:00001976
147146 16:21:41.1065470s - TransmitResponse: sent 134.155.55.138:123->134.155.4.35:123
147146 16:22:02.6065470s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +00.0061565s
147146 16:22:02.6065470s - W32TmServiceMain: resync req, reg too soon.
147146 16:22:02.6065470s - W32TmServiceMain: waiting 1.015s
147146 16:22:03.6221720s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 16:22:03.6221720s - W32TmServiceMain: timeout
147146 16:22:03.6221720s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: SO:61565 KPhO:0 PhO:61565 uT:4032 FllPPE:61565 PllPPE:61565 FllPPrE:30782.5 PllPPrE:30782.5 FllRA:3 PllRA:0 RA:1 CR:156251 nT:65 RAPhO:65 SD:67524146 LI:0 S:3 RDl:8889 RDs:78064407 TSF:0x0 Hold(0)
147146 16:22:03.6221720s - W32TmServiceMain: waiting 64.000s
147146 16:22:13.0633816s - Computing server digest: OLD:FALSE, RID:00001976
147146 16:22:13.3290712s - TransmitResponse: sent 134.155.55.138:123->134.155.4.35:123
147146 16:22:29.0810639s - Computing server digest: OLD:FALSE, RID:00001976
147146 16:22:29.1123157s - TransmitResponse: sent 134.155.55.138:123->134.155.4.35:123
147146 16:22:45.0972030s - Computing server digest: OLD:FALSE, RID:00001976
147146 16:22:45.1753295s - TransmitResponse: sent 134.155.55.138:123->134.155.4.35:123
147146 16:23:06.6286744s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: -00.0075142s
147146 16:23:06.6286744s - W32TmServiceMain: resync req, reg too soon.
147146 16:23:06.6286744s - W32TmServiceMain: waiting 0.993s
147146 16:23:07.6286808s - W32TmServiceMain: timeout
147146 16:23:07.6286808s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: *STALE*
147146 16:23:07.6286808s - W32TmServiceMain: waiting 64.000s
147146 16:23:07.6599310s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 16:24:10.6290840s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: -00.0093220s
147146 16:24:10.6290840s - W32TmServiceMain: resync req, reg too soon.
147146 16:24:10.6290840s - W32TmServiceMain: waiting 0.999s
147146 16:24:11.6290904s - W32TmServiceMain: timeout
147146 16:24:11.6290904s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: *STALE*
147146 16:24:11.6290904s - W32TmServiceMain: waiting 64.000s
147146 16:24:11.6759657s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 16:25:15.6451251s - W32TmServiceMain: timeout
147146 16:25:15.6451251s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: *STALE*
147146 16:25:15.6451251s - W32TmServiceMain: waiting 64.000s
147146 16:25:15.6920004s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 16:25:16.6451315s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: -00.0172314s
147146 16:25:16.6451315s - W32TmServiceMain: resync req, irreg now pending.
147146 16:25:16.6451315s - W32TmServiceMain: waiting i14.999s (62.999s)
147146 16:25:31.6452275s - W32TmServiceMain: timeout
147146 16:25:31.6452275s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: *STALE*
147146 16:25:31.6452275s - W32TmServiceMain: waiting 64.000s
147146 16:26:19.6924100s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 16:26:20.6455411s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: -00.0159730s
147146 16:26:20.6611662s - W32TmServiceMain: resync req, reg too soon.
147146 16:26:20.6611662s - W32TmServiceMain: waiting 14.984s
147146 16:26:35.6612622s - W32TmServiceMain: timeout
147146 16:26:35.6612622s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: *STALE*
147146 16:26:35.6612622s - W32TmServiceMain: waiting 64.000s
147146 16:27:23.7240698s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 16:27:24.6615758s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: -00.0224873s
147146 16:27:24.6615758s - W32TmServiceMain: resync req, reg too soon.
147146 16:27:24.6615758s - W32TmServiceMain: waiting 14.999s
147146 16:27:39.6616718s - W32TmServiceMain: timeout
147146 16:27:39.6616718s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: *STALE*
147146 16:27:39.6616718s - W32TmServiceMain: waiting 64.000s
147146 16:28:27.7401045s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 16:28:28.6776105s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: -00.0289885s
147146 16:28:28.6776105s - W32TmServiceMain: resync req, reg too soon.
147146 16:28:28.6776105s - W32TmServiceMain: waiting 14.984s
147146 16:28:43.6620814s - W32TmServiceMain: timeout
147146 16:28:43.6620814s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: SO:-289885 KPhO:638 PhO:-290523 uT:24708 FllPPE:-339939 PllPPE:-265815 FllPPrE:172734 PllPPrE:136426 FllRA:-2 PllRA:-11 RA:-7 CR:156244 nT:959 RAPhO:-6713 SD:67524747 LI:0 S:3 RDl:-6461 RDs:860417 TSF:0x0 Hold(1)
147146 16:28:43.6620814s - W32TmServiceMain: waiting 64.000s
147146 16:29:31.7558990s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 16:29:33.6464031s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +00.0038919s
147146 16:29:33.6464031s - W32TmServiceMain: resync req, reg too soon.
147146 16:29:33.6464031s - W32TmServiceMain: waiting 14.015s
147146 16:29:47.6613172s - W32TmServiceMain: timeout
147146 16:29:47.6613172s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: *STALE*
147146 16:29:47.6613172s - W32TmServiceMain: waiting 64.000s
147146 16:30:35.7688064s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 16:30:37.6593588s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +00.0004509s
147146 16:30:37.6593588s - W32TmServiceMain: resync req, reg too soon.
147146 16:30:37.6593588s - W32TmServiceMain: waiting 14.001s
147146 16:30:51.6744456s - W32TmServiceMain: timeout
147146 16:30:51.6744456s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: *STALE*
147146 16:30:51.6744456s - W32TmServiceMain: waiting 64.000s
147146 16:31:39.7819732s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 16:31:42.6568628s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +00.0047145s
147146 16:31:42.6568628s - W32TmServiceMain: resync req, reg too soon.
147146 16:31:42.6568628s - W32TmServiceMain: waiting 13.017s
147146 16:31:55.6876124s - W32TmServiceMain: timeout
147146 16:31:55.6876124s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: *STALE*
147146 16:31:55.6876124s - W32TmServiceMain: waiting 64.000s
147146 16:32:43.7951400s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 16:32:48.6699528s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +00.0011551s
147146 16:32:48.6699528s - W32TmServiceMain: resync req, reg too soon.
147146 16:32:48.6699528s - W32TmServiceMain: waiting 11.017s
147146 16:32:59.7007792s - W32TmServiceMain: timeout
147146 16:32:59.7007792s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: *STALE*
147146 16:32:59.7007792s - W32TmServiceMain: waiting 64.000s
147146 16:33:47.8083068s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 16:33:54.6674184s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +00.0054834s
147146 16:33:54.6674184s - W32TmServiceMain: resync req, reg too soon.
147146 16:33:54.6674184s - W32TmServiceMain: waiting 9.033s
147146 16:34:03.7295704s - W32TmServiceMain: timeout
147146 16:34:03.7295704s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: *STALE*
147146 16:34:03.7295704s - W32TmServiceMain: waiting 64.000s
147146 16:34:51.8214736s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 16:34:57.6806236s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +00.0017859s
147146 16:34:57.6806236s - W32TmServiceMain: resync req, reg too soon.
147146 16:34:57.6806236s - W32TmServiceMain: waiting 10.048s
147146 16:35:07.7427372s - W32TmServiceMain: timeout
147146 16:35:07.7427372s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: SO:4509 KPhO:-660 PhO:5169 uT:8257 FllPPE:-36116 PllPPE:38197 FllPPrE:173676 PllPPrE:137756 FllRA:0 PllRA:0 RA:0 CR:156244 nT:17286 RAPhO:0 SD:55126969 LI:0 S:3 RDl:-28907 RDs:658118 TSF:0x0 Hold(2)
147146 16:35:07.7427372s - W32TmServiceMain: waiting 64.000s
147146 16:35:55.8350244s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 16:36:11.7562880s - W32TmServiceMain: timeout
147146 16:36:11.7562880s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: *STALE*
147146 16:36:11.7562880s - W32TmServiceMain: waiting 64.000s
147146 16:36:59.8481912s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 16:37:07.6916400s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +00.0019824s
147146 16:37:07.6916400s - W32TmServiceMain: resync req, reg too soon.
147146 16:37:07.6916400s - W32TmServiceMain: waiting 8.064s
147146 16:37:15.7694548s - W32TmServiceMain: timeout
147146 16:37:15.7694548s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: *STALE*
147146 16:37:15.7694548s - W32TmServiceMain: waiting 64.000s
147146 16:37:45.1277024s - Computing server digest: OLD:FALSE, RID:00001976
147146 16:37:45.1745756s - TransmitResponse: sent 134.155.55.138:123->134.155.4.35:123
147146 16:38:03.8769824s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 16:38:12.7047684s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: -00.0015654s
147146 16:38:12.7047684s - W32TmServiceMain: resync req, reg too soon.
147146 16:38:12.7047684s - W32TmServiceMain: waiting 7.064s
147146 16:38:19.7826216s - W32TmServiceMain: timeout
147146 16:38:19.7826216s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: SO:-15654 KPhO:669 PhO:-16323 uT:29124 FllPPE:-16323 PllPPE:-16323 FllPPrE:173867 PllPPrE:137998 FllRA:0 PllRA:0 RA:0 CR:156244 nT:453 RAPhO:0 SD:38985421 Poll++ LI:0 S:3 RDl:-25421 RDs:706117 TSF:0x0 Hold(3)
147146 16:38:19.7826216s - TimeProvCommand([NtpClient], TPC_PollIntervalChanged) called.
147146 16:38:19.7826216s - TimeProvCommand([NtpServer], TPC_PollIntervalChanged) called.
147146 16:38:19.7826216s - W32TmServiceMain: waiting 128.000s
147146 16:38:19.7826216s - PeerPollingThread: PeerListUpdated
147146 16:39:07.8886516s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 16:39:17.7007748s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +00.0040453s
147146 16:39:17.7007748s - W32TmServiceMain: resync req, irreg now pending.
147146 16:39:17.7007748s - W32TmServiceMain: waiting i0.000s (70.081s)
147146 16:39:17.7163992s - W32TmServiceMain: timeout
147146 16:39:17.7163992s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: *STALE*
147146 16:39:17.7163992s - W32TmServiceMain: waiting 70.066s
147146 16:40:11.9018184s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 16:40:20.7139800s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +00.0004218s
147146 16:40:20.7139800s - W32TmServiceMain: resync req, reg too soon.
147146 16:40:20.7139800s - W32TmServiceMain: waiting 7.068s
147146 16:40:27.7918332s - W32TmServiceMain: timeout
147146 16:40:27.7918332s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: SO:4218 KPhO:-678 PhO:4896 uT:8193 FllPPE:4896 PllPPE:4896 FllPPrE:171138 PllPPrE:134543 FllRA:0 PllRA:0 RA:0 CR:156244 nT:453 RAPhO:0 SD:543157 LI:0 S:3 RDl:-44320 RDs:520033 TSF:0x0 Hold(4)->Sync
147146 16:40:27.7918332s - W32TmServiceMain: waiting 128.000s
147146 16:41:15.9153372s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 16:41:26.7117976s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +00.0043066s
147146 16:41:26.7117976s - W32TmServiceMain: resync req, irreg now pending.
147146 16:41:26.7117976s - W32TmServiceMain: waiting i0.000s (69.080s)
147146 16:41:26.7274220s - W32TmServiceMain: timeout
147146 16:41:26.7274220s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: *STALE*
147146 16:41:26.7274220s - W32TmServiceMain: waiting 69.064s
147146 16:42:19.9441284s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 16:42:29.7250028s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +00.0005279s
147146 16:42:29.7250028s - W32TmServiceMain: resync req, reg too soon.
147146 16:42:29.7250028s - W32TmServiceMain: waiting 6.066s
147146 16:42:35.8028944s - W32TmServiceMain: timeout
147146 16:42:35.8028944s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: *STALE*
147146 16:42:35.8028944s - W32TmServiceMain: waiting 128.000s
147146 16:43:23.9572952s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 16:43:32.7382080s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: -00.0031741s
147146 16:43:32.7382080s - W32TmServiceMain: resync req, irreg now pending.
147146 16:43:32.7382080s - W32TmServiceMain: waiting i0.000s (71.064s)
147146 16:43:32.7538324s - W32TmServiceMain: timeout
147146 16:43:32.7538324s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: *STALE*
147146 16:43:32.7538324s - W32TmServiceMain: waiting 128.000s
147146 16:44:27.9704620s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 16:44:36.7357504s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +00.0009323s
147146 16:44:36.7357504s - W32TmServiceMain: resync req, irreg now pending.
147146 16:44:36.7357504s - W32TmServiceMain: waiting i0.000s (64.018s)
147146 16:44:36.7513748s - W32TmServiceMain: timeout
147146 16:44:36.7513748s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: *STALE*
147146 16:44:36.7513748s - W32TmServiceMain: waiting 128.000s
147146 16:45:31.9836288s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 16:45:40.7489172s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: -00.0027445s
147146 16:45:40.7489172s - W32TmServiceMain: resync req, irreg now pending.
147146 16:45:40.7489172s - W32TmServiceMain: waiting i0.000s (64.002s)
147146 16:45:40.7645416s - W32TmServiceMain: timeout
147146 16:45:40.7645416s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: *STALE*
147146 16:45:40.7645416s - W32TmServiceMain: waiting 128.000s
147146 16:46:17.1693936s - Computing server digest: OLD:FALSE, RID:00001976
147146 16:46:17.2162668s - TransmitResponse: sent 134.155.55.138:123->134.155.4.35:123
147146 16:46:35.9967956s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 16:46:45.7464212s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +00.0015036s
147146 16:46:45.7464212s - W32TmServiceMain: resync req, irreg now pending.
147146 16:46:45.7464212s - W32TmServiceMain: waiting i0.000s (63.018s)
147146 16:46:45.7620456s - W32TmServiceMain: timeout
147146 16:46:45.7620456s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: SO:5279 KPhO:698 PhO:4581 uT:8257 FllPPE:4581 PllPPE:4581 FllPPrE:20098.3 PllPPrE:21038.1 FllRA:0 PllRA:0 RA:0 CR:156244 nT:16387 RAPhO:0 SD:893114 LI:0 S:4 RDl:-28250 RDs:1628172 TSF:0x0 Sync
147146 16:46:45.7620456s - W32TmServiceMain: waiting 128.000s
147146 16:47:40.0260476s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 16:47:49.7600488s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: -00.0026457s
147146 16:47:49.7600488s - W32TmServiceMain: resync req, irreg now pending.
147146 16:47:49.7600488s - W32TmServiceMain: waiting i0.000s (64.001s)
147146 16:47:49.7756732s - W32TmServiceMain: timeout
147146 16:47:49.7756732s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: SO:-26457 KPhO:671 PhO:-27128 uT:20483 FllPPE:-27128 PllPPE:-27128 FllPPrE:16181.2 PllPPrE:16181.2 FllRA:0 PllRA:0 RA:0 CR:156244 nT:1 RAPhO:0 SD:1146663 (i) LI:0 S:4 RDl:1158 RDs:1642800 TSF:0x0 Sync
147146 16:47:49.7756732s - W32TmServiceMain: waiting 63.986s
147146 16:48:44.0366352s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 16:48:53.7706364s - W32TmServiceMain: timeout
147146 16:48:53.7706364s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: *STALE*
147146 16:48:53.7706364s - W32TmServiceMain: waiting 128.000s
147146 16:48:55.7549352s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +00.0042118s
147146 16:48:55.7549352s - W32TmServiceMain: resync req, irreg now pending.
147146 16:48:55.7549352s - W32TmServiceMain: waiting i14.015s (126.015s)
147146 16:49:09.7700220s - W32TmServiceMain: timeout
147146 16:49:09.7700220s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: SO:42118 KPhO:-665 PhO:42783 uT:4224 FllPPE:42783 PllPPE:42783 FllPPrE:25550.3 PllPPrE:25550.3 FllRA:2 PllRA:0 RA:1 CR:156245 nT:897 RAPhO:897 SD:1130795 LI:0 S:3 RDl:-127948 RDs:572039 TSF:0x0 Sync
147146 16:49:09.7700220s - W32TmServiceMain: waiting 128.000s
147146 16:49:48.0542106s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 16:51:05.7861713s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: -00.0075874s
147146 16:51:05.7861713s - W32TmServiceMain: resync req, reg too soon.
147146 16:51:05.7861713s - W32TmServiceMain: waiting 11.983s
147146 16:51:17.7701628s - W32TmServiceMain: timeout
147146 16:51:17.7701628s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: SO:-75874 KPhO:647 PhO:-76521 uT:8322 FllPPE:-84843 PllPPE:-68199 FllPPrE:49461.2 PllPPrE:42539.4 FllRA:-2 PllRA:0 RA:0 CR:156245 nT:767 RAPhO:0 SD:1139990 Poll++ LI:0 S:3 RDl:13529 RDs:609858 TSF:0x0 Sync
147146 16:51:17.7701628s - TimeProvCommand([NtpClient], TPC_PollIntervalChanged) called.
147146 16:51:17.7701628s - TimeProvCommand([NtpServer], TPC_PollIntervalChanged) called.
147146 16:51:17.7701628s - W32TmServiceMain: waiting 256.000s
147146 16:51:17.7701628s - PeerPollingThread: PeerListUpdated
147146 16:51:56.0584530s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 16:53:13.7901798s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: -00.0004749s
147146 16:53:13.7901798s - W32TmServiceMain: resync req, irreg now pending.
147146 16:53:13.7901798s - W32TmServiceMain: waiting i0.000s (139.979s)
147146 16:53:13.8058043s - W32TmServiceMain: timeout
147146 16:53:13.8058043s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: *STALE*
147146 16:53:13.8058043s - W32TmServiceMain: waiting 139.964s
147146 16:54:49.1933768s - Computing server digest: OLD:FALSE, RID:00001976
147146 16:54:49.5683648s - TransmitResponse: sent 134.155.55.138:123->134.155.4.35:123
147146 16:55:23.8016443s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: -00.0000266s
147146 16:55:23.8016443s - W32TmServiceMain: resync req, reg too soon.
147146 16:55:23.8016443s - W32TmServiceMain: waiting 9.968s
147146 16:55:33.7700753s - W32TmServiceMain: timeout
147146 16:55:33.7700753s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: *STALE*
147146 16:55:33.7700753s - W32TmServiceMain: waiting 256.000s
147146 16:56:12.0813493s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 16:59:05.2164338s - Computing server digest: OLD:FALSE, RID:00001976
147146 16:59:05.5601728s - TransmitResponse: sent 134.155.55.138:123->134.155.4.35:123
147146 16:59:39.8247013s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: -00.0003398s
147146 16:59:39.8247013s - W32TmServiceMain: resync req, reg too soon.
147146 16:59:39.8247013s - W32TmServiceMain: waiting 9.945s
147146 16:59:49.7775078s - W32TmServiceMain: timeout
147146 16:59:49.7775078s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: SO:-3398 KPhO:-674 PhO:-2724 uT:32900 FllPPE:63076 PllPPE:-2724 FllPPrE:58615.8 PllPPrE:42499.5 FllRA:0 PllRA:0 RA:0 CR:156245 nT:637 RAPhO:0 SD:879220 LI:0 S:3 RDl:-48006 RDs:578653 TSF:0x0 Sync
147146 16:59:49.7775078s - W32TmServiceMain: waiting 256.000s
147146 17:00:28.0885066s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 17:00:52.7595921s - TransmitResponse: sent 134.155.55.138:123->134.155.55.191:13999
147146 17:01:13.2276871s - Computing server digest: OLD:FALSE, RID:00001976
147146 17:01:13.3058096s - TransmitResponse: sent 134.155.55.138:123->134.155.4.35:123
147146 17:01:52.7576721s - TransmitResponse: sent 134.155.55.138:123->134.155.55.191:13999
147146 17:02:17.2256391s - Computing server digest: OLD:FALSE, RID:00001976
147146 17:02:17.6006271s - TransmitResponse: sent 134.155.55.138:123->134.155.4.35:123
147146 17:02:49.2402396s - Computing server digest: OLD:FALSE, RID:00001976
147146 17:02:49.5058561s - TransmitResponse: sent 134.155.55.138:123->134.155.4.35:123
147146 17:02:52.7557521s - TransmitResponse: sent 134.155.55.138:123->134.155.55.191:13999
147146 17:03:05.2397276s - Computing server digest: OLD:FALSE, RID:00001976
147146 17:03:05.3178501s - TransmitResponse: sent 134.155.55.138:123->134.155.4.35:123
147146 17:03:52.7538321s - TransmitResponse: sent 134.155.55.138:123->134.155.55.191:13999
147146 17:03:57.8474191s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: -00.0009773s
147146 17:03:57.8474191s - W32TmServiceMain: resync req, reg too soon.
147146 17:03:57.8474191s - W32TmServiceMain: waiting 7.930s
147146 17:04:05.7846651s - W32TmServiceMain: timeout
147146 17:04:05.7846651s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: *STALE*
147146 17:04:05.7846651s - W32TmServiceMain: waiting 256.000s
147146 17:04:44.0959391s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 17:04:52.7519121s - TransmitResponse: sent 134.155.55.138:123->134.155.55.191:13999
147146 17:05:52.7499921s - TransmitResponse: sent 134.155.55.138:123->134.155.55.191:13999
147146 17:06:52.7480721s - TransmitResponse: sent 134.155.55.138:123->134.155.55.191:13999
147146 17:08:13.8861006s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: -00.0093708s
147146 17:08:13.8861006s - W32TmServiceMain: resync req, reg too soon.
147146 17:08:13.8861006s - W32TmServiceMain: waiting 7.898s
147146 17:08:21.7920976s - W32TmServiceMain: timeout
147146 17:08:21.7920976s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: *STALE*
147146 17:08:21.7920976s - W32TmServiceMain: waiting 256.000s
147146 17:09:00.1033716s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 17:12:29.9091576s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: -00.0102016s
147146 17:12:29.9091576s - W32TmServiceMain: resync req, reg too soon.
147146 17:12:29.9091576s - W32TmServiceMain: waiting 7.882s
147146 17:12:37.7995301s - W32TmServiceMain: timeout
147146 17:12:37.7995301s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: *STALE*
147146 17:12:37.7995301s - W32TmServiceMain: waiting 256.000s
147146 17:13:16.1108041s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 17:16:46.9321826s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: -00.0107408s
147146 17:16:46.9321826s - W32TmServiceMain: resync req, reg too soon.
147146 17:16:46.9321826s - W32TmServiceMain: waiting 6.867s
147146 17:16:53.8069626s - W32TmServiceMain: timeout
147146 17:16:53.8069626s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: SO:-107408 KPhO:-646 PhO:-106762 uT:65737 FllPPE:-106762 PllPPE:-106762 FllPPrE:78111.2 PllPPrE:66871.2 FllRA:0 PllRA:-10 RA:-5 CR:156240 nT:440 RAPhO:-2200 SD:487872 LI:0 S:3 RDl:-58733 RDs:596858 TSF:0x0 Sync
147146 17:16:53.8069626s - W32TmServiceMain: waiting 256.000s
147146 17:17:32.1219830s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 17:18:05.3696138s - Computing server digest: OLD:FALSE, RID:00001976
147146 17:18:05.4477338s - TransmitResponse: sent 134.155.55.138:123->134.155.4.35:123
147146 17:21:04.9362458s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +00.0039606s
147146 17:21:04.9362458s - W32TmServiceMain: resync req, reg too soon.
147146 17:21:04.9362458s - W32TmServiceMain: waiting 4.870s
147146 17:21:09.8109338s - W32TmServiceMain: timeout
147146 17:21:09.8109338s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: *STALE*
147146 17:21:09.8109338s - W32TmServiceMain: waiting 256.000s
147146 17:21:48.1366058s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 17:25:19.9667978s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +00.0037767s
147146 17:25:19.9667978s - W32TmServiceMain: resync req, reg too soon.
147146 17:25:19.9667978s - W32TmServiceMain: waiting 5.844s
147146 17:25:25.8257978s - W32TmServiceMain: timeout
147146 17:25:25.8257978s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: SO:37767 KPhO:-680 PhO:38447 uT:32837 FllPPE:-125738 PllPPE:202632 FllPPrE:97960.6 PllPPrE:119495 FllRA:0 PllRA:1 RA:0 CR:156240 nT:375 RAPhO:0 SD:500585 LI:0 S:3 RDl:-50978 RDs:622576 TSF:0x0 Sync
147146 17:25:25.8257978s - W32TmServiceMain: waiting 256.000s
147146 17:26:04.1551199s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 17:26:37.4030538s - Computing server digest: OLD:FALSE, RID:00001976
147146 17:26:37.4811738s - TransmitResponse: sent 134.155.55.138:123->134.155.4.35:123
147146 17:27:06.1512138s - Computing server digest: OLD:FALSE, RID:00001976
147146 17:27:06.4168218s - TransmitResponse: sent 134.155.55.138:123->134.155.4.35:123
147146 17:29:41.8287498s - W32TmServiceMain: timeout
147146 17:29:41.8287498s - TimeProvCommand([NtpClient], TPC_GetSamples) called.
ClockDispln Update: *STALE*
147146 17:29:41.8287498s - W32TmServiceMain: waiting 256.000s
147146 17:30:20.1700458s - Polling peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
147146 17:30:25.9821738s - Response from peer rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +00.0142532s
147146 17:30:25.9821738s - PeerPollingThread: PeerListUpdated
147146 17:30:25.9821738s - W32TmServiceMain: resync req, irreg now pending.
147146 17:30:25.9821738s - W32TmServiceMain: waiting i0.000s (211.846s)
147146 17:30:25.9977978s - W32TmServiceMain: timeout
147146 17:30:25.9977978s - TimeProvCommand([NtpClient], TPC_GetSamples) called.

The DC is both client and server for time sync. It runs in symmetric active
mode in W2K3 by default. There is a known issue with some UNIX
implementations that have trouble to respond symmetric-active mode request.
I am not sure if this is one since more detailed log is needed, but I will
give it a try first.

Windows Time Service can force a Client mode request to solve the problem by
adding special flag ",0x8" when set the manual peer, such as
"time.windows.com,0x8".

Follow the instruction here:

1. w32tm /config /manualpeerlist:time.windows.com,0x8 /syncfromflags:MANUAL

2. net stop w32time & net start w32time

3. w32tm /resync.

-

Yi Zhao

Windows Time Service, Directory Services.

This posting is provided "AS IS" with no warranties, and confers no rights.



"Stefan Holland" <(E-Mail Removed)> wrote in message
news:bpe2c0$fsr$(E-Mail Removed)...
> Hello,
> we have upgraded our domain from Windows NT to Windows 2003 a few weeks
ago.
>
> Since then we are unable to sync our domain time with an NTP server on a
> Unix system.
>
> The same NTP server has been succesfully contacted by our NT DC before
> and still can be used by a Windows 2000 member server (see Windows
> 2000.log).
>
> The ntp client on the Windows 2003 Server somehow does not like the NTP
> data (see Windows 2003.log). We have tried serveral NTP servers, but our
> Windows 2003 server does not like any of them.
>
> I would very much appreciate any hint what its problem could be or what
> has been changed in the w32time service from Windows 2000 to Windows 2003.
>
> Regards,
> Stefan
>
>
>
>
>
>
>


----------------------------------------------------------------------------
----


> W32Time: BEGIN:InitAdjIncr
> W32Time: Adj 156250 , Incr 156250 fAdjust 0
> W32Time: END:Line 2503
> W32Time: BEGIN:TsUpTheThread
> W32Time: END Line 1407
> W32Time: TimeMMInit()
> W32Time: Kernel timer : using default maximum resolution
> W32Time: MaximumTime = 156250
> W32Time: CurrentTime = 156250
> W32Time: Timer calibrated, looped 1 times
> W32Time: BEGIN:InitTmCfg
> W32Time: END:Line 807
> W32Time: BEGIN:InitTmCli
> W32Time: END:Line 2596
> W32Time: BEGIN:InitTmData
> W32Time: END:Line 2618
> W32Time: AvoidTimeSyncOnWan 0
> W32Time: ntpserver - rumtime4.uni-mannheim.de
> W32Time: BEGIN:CMOSSynchSet
> W32Time: Setting adjustment 156250 - Bool 0
> W32Time: BEGIN:SetTSTimeRes
> W32Time: END:Line 1295
> W32Time: END:Line 864
> W32Time: BEGIN:InitializeDC
> W32Time: BEGIN:GetRole
> W32Time: Role is 'workstation'
> W32Time: END Line 672
> W32Time: END:Line 704
> W32Time: timeBeginPeriod: setting resolution 9
> W32Time: BEGIN:TimeSync
> W32Time: BEGIN:FGetType
> W32Time: END Line 254
> W32Time: BEGIN:FDoTimeNTPType
> W32Time: BEGIN:ChooseNTPServer
> W32Time: END Line 2178
> W32Time: BEGIN:GetSocketForSynch
> W32Time: NTP: ntpptrs[0] - RUMTIME4.UNI-MANNHEIM.DE
> W32Time: rgbNTPServer RUMTIME4.UNI-MANNHEIM.DE
> W32Time: Port Pinging to - 123
> W32Time: Connecting to "RUMTIME4.UNI-MANNHEIM.DE"
(134.155.51.118)
> W32Time: END:Line 1170
> W32Time: BEGIN:GetDefaultRid
> W32Time: END Line 2359
> W32Time: BEGIN:ComputeDelay
> W32Time: BEGIN:NTPTry -- init
> W32Time: END Line 1683
> W32Time: BEGIN:NTPTry -- try
> W32Time: BEGIN:ComputeInterval
> W32Time: END Line 2479
> W32Time: Sending to server 48 bytes...
> W32Time: Recv'ed from server 48 Bytes...
> W32Time: END Line 1885
> W32Time: BEGIN:NTPTry -- delay
> W32Time: END Line 2012
> W32Time: Round trip was 0ms
> W32Time: BEGIN:NTPTry -- gettime
> W32Time: BEGIN:Fgmtimetonttime
> W32Time: END Line 2563
> W32Time: END Line 1998
> W32Time: one-way delay is 0ms
> W32Time: END Line 1645
> W32Time: END Line 368
> W32Time: BEGIN:TimeDiff
> W32Time: ClockError -1209
> W32Time: END Line 2542
> W32Time: BEGIN:FCheckTimeSanity
> W32Time: Adjusting time by 1209 ms. No eventlog messages since time
difference is 0 <1 minute
> W32Time: END Line 570
> W32Time: BEGIN:SetTimeNow
> W32Time: Skewing for backwards, badj, btime = 78125 2418
> W32Time: END Line 1280
> W32Time: Time was 00min 22.718s
> W32Time: Time is 00min 21.509s
> W32Time: Error 1209ms
> W32Time: BEGIN:CheckLeapFlag
> W32Time: END:Line 606
> W32Time: BEGIN:ComputePostTimeData
> W32Time: BEGIN:ComputeInterval
> W32Time: END Line 2479
> W32Time: BEGIN:ComputeSleepStuff
> W32Time: Computed stagger is 0ms, bias is 0ms
> W32Time: Time until next sync - 2699.960s
> W32Time: END:Line 816
> W32Time: END:Line 221
> W32Time: END:Line 196
> W32Time: BEGIN:TermTime
> W32Time: TimeMMCleanup()
> W32Time: BEGIN:FinishCleanup
> W32Time: BEGIN:TsUpTheThread
> W32Time: END Line 1407
> W32Time: Time service stopped.
> W32Time: END:Line 407
>
>


----------------------------------------------------------------------------
----


> 147146 16:11:46.4375000s - ---------- Log File Opened -----------------
> 147146 16:11:46.4375000s - Entered W32TmServiceMain
> 147146 16:11:46.4375000s - CurSpc:15625000ns BaseSpc:15625000ns
SyncToCmos:Yes
> 147146 16:11:46.4375000s - PerfFreq:3579545c/s
> 147146 16:11:46.4375000s - Time zone OK.
> 147146 16:11:46.4375000s - DomainHierarchy: LSA role change
notification. Redetecting.
> 147146 16:11:46.4375000s - DomainHierarchy: we are now the domain
root. Should be advertised as reliable
> 147146 16:11:46.4375000s - ClockDisciplineThread: Starting:147146
16:11:46.4375000s - LI:0 S:1 RDl:0 RDs:100000000 TSF:0x0
> 147146 16:11:46.4375000s - ClockDispln: we're a reliable time service with
no time source: LS: 0, TN: 864000000000, WAIT: 86400000
> 147146 16:11:46.4375000s - Starting Providers.
> 147146 16:11:46.4375000s - Starting 'NtpClient',
dll:'F:\WINDOWS\system32\w32time.dll'
> 147146 16:11:46.4375000s - NtpTimeProvOpen("NtpClient") called.
> 147146 16:11:46.4375000s - NtpProvider: Created 2 sockets (1 listen-only):
134.155.55.138:123, (127.0.0.1:123)
> 147146 16:11:46.4375000s - NtpClient started.
> 147146 16:11:46.4375000s - Starting 'NtpServer',
dll:'F:\WINDOWS\system32\w32time.dll'
> 147146 16:11:46.4375000s - NtpTimeProvOpen("NtpServer") called.
> 147146 16:11:46.4375000s - PeerPollingThread: PeerListUpdated
> 147146 16:11:46.4375000s - Resolving rumtime4.uni-mannheim.de
> 147146 16:11:46.4375000s - NtpServer started.
> 147146 16:11:46.4375000s - Successfully started 2 providers.
> 147146 16:11:46.4375000s - W32TmServiceMain: waiting i16.000s (64.000s)
> 147146 16:11:46.4375000s - PeerPollingThread: PeerListUpdated
> 147146 16:11:46.4375000s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 16:11:46.4375000s - W32TmServiceMain: waiting i16.000s (64.000s)
> 147146 16:11:48.1875000s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +425.4126690s
> 147146 16:11:48.1875000s - W32TmServiceMain: resync req, irreg already
pending.
> 147146 16:11:48.1875000s - W32TmServiceMain: waiting i14.250s (62.250s)
> 147146 16:11:48.4375000s - W32TimeHandler called:
SERVICE_CONTROL_INTERROGATE
> 147146 16:12:02.4375000s - W32TmServiceMain: timeout
> 147146 16:12:02.4375000s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: SO:4254126690 KPhO:0 *PhO:4254126690 uT:112
SD:38995981 LI:0 S:3 RDl:118412 RDs:4332118653 TSF:0x0 Unset->Hold
> 147146 16:19:07.8501690s - Logging information: The time service is now
synchronizing the system time with the time source rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123).
> 147146 16:19:07.8501690s - TimeProvCommand([NtpClient], TPC_TimeJumped)
called.
> 147146 16:19:07.8501690s - TimeProvCommand([NtpServer], TPC_TimeJumped)
called.
> 147146 16:19:07.8501690s - W32TmServiceMain: waiting 64.000s
> 147146 16:19:07.8501690s - PeerPollingThread: PeerListUpdated
> 147146 16:19:07.8501690s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 16:19:33.0220440s - Computing server digest: OLD:FALSE,
RID:00001976
> 147146 16:19:33.0689190s - TransmitResponse: sent
134.155.55.138:123->134.155.4.35:123
> 147146 16:10:53.8906250s - Ignoring packet that failed tests from
rumtime4.uni-mannheim.de (ntp.m|0x0|134.155.55.138:123->134.155.51.118:123).
> 147146 16:10:57.5312500s - RPC Caller is VWLPOOL\stefan
(S-1-5-21-77879606-1260280510-1874078741-2586)
> 147146 16:10:57.5312500s - RPC Call - HardResync
> 147146 16:10:57.5312500s - W32TmServiceMain: ********** Time Slip
Notification **********
> 147146 16:10:57.5312500s - ClockDispln TimeSlip:147146 16:10:57.5312500s -
LI:0 S:3 RDl:0 RDs:100000000 TSF:0x0
> 147146 16:10:57.5310000s - ClockDispln: we're a reliable time service with
no time source: LS: 0, TN: 864000000000, WAIT: 86400000
> 147146 16:10:57.5310000s - TimeProvCommand([NtpClient], TPC_TimeJumped)
called.
> 147146 16:10:57.5310000s - TimeProvCommand([NtpServer], TPC_TimeJumped)
called.
> 147146 16:10:57.5310000s - W32TmServiceMain: waiting i16.000s (64.000s)
> 147146 16:10:57.5466250s - PeerPollingThread: PeerListUpdated
> 147146 16:10:57.5466250s - PeerPollingThread: PeerListUpdated
> 147146 16:10:59.0310000s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 16:11:13.5622500s - W32TmServiceMain: timeout
> 147146 16:11:13.5622500s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> 147146 16:11:13.5622500s - W32TmServiceMain: waiting 64.000s
> 147146 16:11:33.3747500s - Computing server digest: OLD:FALSE,
RID:00001976
> 147146 16:11:33.4372500s - TransmitResponse: sent
134.155.55.138:123->134.155.4.35:123
> 147146 16:11:55.9528750s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +543.6536720s
> 147146 16:11:55.9528750s - W32TmServiceMain: resync req, irreg now
pending.
> 147146 16:11:55.9528750s - W32TmServiceMain: waiting i0.000s (21.609s)
> 147146 16:11:55.9685000s - W32TmServiceMain: timeout
> 147146 16:11:55.9685000s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: SO:5436536720 KPhO:0 *PhO:5436536720 uT:3739
SD:55135271 LI:0 S:3 RDl:6199 RDs:5514490849 TSF:0x0 Unset->Hold
> 147146 16:20:59.6221720s - TimeProvCommand([NtpClient], TPC_TimeJumped)
called.
> 147146 16:20:59.6221720s - TimeProvCommand([NtpServer], TPC_TimeJumped)
called.
> 147146 16:20:59.6221720s - W32TmServiceMain: waiting 64.000s
> 147146 16:20:59.6221720s - PeerPollingThread: PeerListUpdated
> 147146 16:20:59.6221720s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 16:21:41.0440470s - Computing server digest: OLD:FALSE,
RID:00001976
> 147146 16:21:41.1065470s - TransmitResponse: sent
134.155.55.138:123->134.155.4.35:123
> 147146 16:22:02.6065470s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +00.0061565s
> 147146 16:22:02.6065470s - W32TmServiceMain: resync req, reg too soon.
> 147146 16:22:02.6065470s - W32TmServiceMain: waiting 1.015s
> 147146 16:22:03.6221720s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 16:22:03.6221720s - W32TmServiceMain: timeout
> 147146 16:22:03.6221720s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: SO:61565 KPhO:0 PhO:61565 uT:4032 FllPPE:61565
PllPPE:61565 FllPPrE:30782.5 PllPPrE:30782.5 FllRA:3 PllRA:0 RA:1 CR:156251
nT:65 RAPhO:65 SD:67524146 LI:0 S:3 RDl:8889 RDs:78064407 TSF:0x0 Hold(0)
> 147146 16:22:03.6221720s - W32TmServiceMain: waiting 64.000s
> 147146 16:22:13.0633816s - Computing server digest: OLD:FALSE,
RID:00001976
> 147146 16:22:13.3290712s - TransmitResponse: sent
134.155.55.138:123->134.155.4.35:123
> 147146 16:22:29.0810639s - Computing server digest: OLD:FALSE,
RID:00001976
> 147146 16:22:29.1123157s - TransmitResponse: sent
134.155.55.138:123->134.155.4.35:123
> 147146 16:22:45.0972030s - Computing server digest: OLD:FALSE,
RID:00001976
> 147146 16:22:45.1753295s - TransmitResponse: sent
134.155.55.138:123->134.155.4.35:123
> 147146 16:23:06.6286744s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: -00.0075142s
> 147146 16:23:06.6286744s - W32TmServiceMain: resync req, reg too soon.
> 147146 16:23:06.6286744s - W32TmServiceMain: waiting 0.993s
> 147146 16:23:07.6286808s - W32TmServiceMain: timeout
> 147146 16:23:07.6286808s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: *STALE*
> 147146 16:23:07.6286808s - W32TmServiceMain: waiting 64.000s
> 147146 16:23:07.6599310s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 16:24:10.6290840s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: -00.0093220s
> 147146 16:24:10.6290840s - W32TmServiceMain: resync req, reg too soon.
> 147146 16:24:10.6290840s - W32TmServiceMain: waiting 0.999s
> 147146 16:24:11.6290904s - W32TmServiceMain: timeout
> 147146 16:24:11.6290904s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: *STALE*
> 147146 16:24:11.6290904s - W32TmServiceMain: waiting 64.000s
> 147146 16:24:11.6759657s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 16:25:15.6451251s - W32TmServiceMain: timeout
> 147146 16:25:15.6451251s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: *STALE*
> 147146 16:25:15.6451251s - W32TmServiceMain: waiting 64.000s
> 147146 16:25:15.6920004s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 16:25:16.6451315s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: -00.0172314s
> 147146 16:25:16.6451315s - W32TmServiceMain: resync req, irreg now
pending.
> 147146 16:25:16.6451315s - W32TmServiceMain: waiting i14.999s (62.999s)
> 147146 16:25:31.6452275s - W32TmServiceMain: timeout
> 147146 16:25:31.6452275s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: *STALE*
> 147146 16:25:31.6452275s - W32TmServiceMain: waiting 64.000s
> 147146 16:26:19.6924100s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 16:26:20.6455411s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: -00.0159730s
> 147146 16:26:20.6611662s - W32TmServiceMain: resync req, reg too soon.
> 147146 16:26:20.6611662s - W32TmServiceMain: waiting 14.984s
> 147146 16:26:35.6612622s - W32TmServiceMain: timeout
> 147146 16:26:35.6612622s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: *STALE*
> 147146 16:26:35.6612622s - W32TmServiceMain: waiting 64.000s
> 147146 16:27:23.7240698s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 16:27:24.6615758s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: -00.0224873s
> 147146 16:27:24.6615758s - W32TmServiceMain: resync req, reg too soon.
> 147146 16:27:24.6615758s - W32TmServiceMain: waiting 14.999s
> 147146 16:27:39.6616718s - W32TmServiceMain: timeout
> 147146 16:27:39.6616718s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: *STALE*
> 147146 16:27:39.6616718s - W32TmServiceMain: waiting 64.000s
> 147146 16:28:27.7401045s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 16:28:28.6776105s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: -00.0289885s
> 147146 16:28:28.6776105s - W32TmServiceMain: resync req, reg too soon.
> 147146 16:28:28.6776105s - W32TmServiceMain: waiting 14.984s
> 147146 16:28:43.6620814s - W32TmServiceMain: timeout
> 147146 16:28:43.6620814s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: SO:-289885 KPhO:638 PhO:-290523 uT:24708
FllPPE:-339939 PllPPE:-265815 FllPPrE:172734 PllPPrE:136426 FllRA:-2
PllRA:-11 RA:-7 CR:156244 nT:959 RAPhO:-6713 SD:67524747 LI:0 S:3 RDl:-6461
RDs:860417 TSF:0x0 Hold(1)
> 147146 16:28:43.6620814s - W32TmServiceMain: waiting 64.000s
> 147146 16:29:31.7558990s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 16:29:33.6464031s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +00.0038919s
> 147146 16:29:33.6464031s - W32TmServiceMain: resync req, reg too soon.
> 147146 16:29:33.6464031s - W32TmServiceMain: waiting 14.015s
> 147146 16:29:47.6613172s - W32TmServiceMain: timeout
> 147146 16:29:47.6613172s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: *STALE*
> 147146 16:29:47.6613172s - W32TmServiceMain: waiting 64.000s
> 147146 16:30:35.7688064s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 16:30:37.6593588s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +00.0004509s
> 147146 16:30:37.6593588s - W32TmServiceMain: resync req, reg too soon.
> 147146 16:30:37.6593588s - W32TmServiceMain: waiting 14.001s
> 147146 16:30:51.6744456s - W32TmServiceMain: timeout
> 147146 16:30:51.6744456s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: *STALE*
> 147146 16:30:51.6744456s - W32TmServiceMain: waiting 64.000s
> 147146 16:31:39.7819732s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 16:31:42.6568628s - Response from peer rumtime4.uni-mannheim.de (nt
p.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +00.0047145s
> 147146 16:31:42.6568628s - W32TmServiceMain: resync req, reg too soon.
> 147146 16:31:42.6568628s - W32TmServiceMain: waiting 13.017s
> 147146 16:31:55.6876124s - W32TmServiceMain: timeout
> 147146 16:31:55.6876124s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: *STALE*
> 147146 16:31:55.6876124s - W32TmServiceMain: waiting 64.000s
> 147146 16:32:43.7951400s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 16:32:48.6699528s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +00.0011551s
> 147146 16:32:48.6699528s - W32TmServiceMain: resync req, reg too soon.
> 147146 16:32:48.6699528s - W32TmServiceMain: waiting 11.017s
> 147146 16:32:59.7007792s - W32TmServiceMain: timeout
> 147146 16:32:59.7007792s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: *STALE*
> 147146 16:32:59.7007792s - W32TmServiceMain: waiting 64.000s
> 147146 16:33:47.8083068s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 16:33:54.6674184s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +00.0054834s
> 147146 16:33:54.6674184s - W32TmServiceMain: resync req, reg too soon.
> 147146 16:33:54.6674184s - W32TmServiceMain: waiting 9.033s
> 147146 16:34:03.7295704s - W32TmServiceMain: timeout
> 147146 16:34:03.7295704s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: *STALE*
> 147146 16:34:03.7295704s - W32TmServiceMain: waiting 64.000s
> 147146 16:34:51.8214736s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 16:34:57.6806236s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +00.0017859s
> 147146 16:34:57.6806236s - W32TmServiceMain: resync req, reg too soon.
> 147146 16:34:57.6806236s - W32TmServiceMain: waiting 10.048s
> 147146 16:35:07.7427372s - W32TmServiceMain: timeout
> 147146 16:35:07.7427372s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: SO:4509 KPhO:-660 PhO:5169 uT:8257 FllPPE:-36116
PllPPE:38197 FllPPrE:173676 PllPPrE:137756 FllRA:0 PllRA:0 RA:0 CR:156244
nT:17286 RAPhO:0 SD:55126969 LI:0 S:3 RDl:-28907 RDs:658118 TSF:0x0 Hold(2)
> 147146 16:35:07.7427372s - W32TmServiceMain: waiting 64.000s
> 147146 16:35:55.8350244s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 16:36:11.7562880s - W32TmServiceMain: timeout
> 147146 16:36:11.7562880s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: *STALE*
> 147146 16:36:11.7562880s - W32TmServiceMain: waiting 64.000s
> 147146 16:36:59.8481912s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 16:37:07.6916400s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +00.0019824s
> 147146 16:37:07.6916400s - W32TmServiceMain: resync req, reg too soon.
> 147146 16:37:07.6916400s - W32TmServiceMain: waiting 8.064s
> 147146 16:37:15.7694548s - W32TmServiceMain: timeout
> 147146 16:37:15.7694548s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: *STALE*
> 147146 16:37:15.7694548s - W32TmServiceMain: waiting 64.000s
> 147146 16:37:45.1277024s - Computing server digest: OLD:FALSE,
RID:00001976
> 147146 16:37:45.1745756s - TransmitResponse: sent
134.155.55.138:123->134.155.4.35:123
> 147146 16:38:03.8769824s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 16:38:12.7047684s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: -00.0015654s
> 147146 16:38:12.7047684s - W32TmServiceMain: resync req, reg too soon.
> 147146 16:38:12.7047684s - W32TmServiceMain: waiting 7.064s
> 147146 16:38:19.7826216s - W32TmServiceMain: timeout
> 147146 16:38:19.7826216s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: SO:-15654 KPhO:669 PhO:-16323 uT:29124 FllPPE:-16323
PllPPE:-16323 FllPPrE:173867 PllPPrE:137998 FllRA:0 PllRA:0 RA:0 CR:156244
nT:453 RAPhO:0 SD:38985421 Poll++ LI:0 S:3 RDl:-25421 RDs:706117 TSF:0x0
Hold(3)
> 147146 16:38:19.7826216s - TimeProvCommand([NtpClient],
TPC_PollIntervalChanged) called.
> 147146 16:38:19.7826216s - TimeProvCommand([NtpServer],
TPC_PollIntervalChanged) called.
> 147146 16:38:19.7826216s - W32TmServiceMain: waiting 128.000s
> 147146 16:38:19.7826216s - PeerPollingThread: PeerListUpdated
> 147146 16:39:07.8886516s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 16:39:17.7007748s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +00.0040453s
> 147146 16:39:17.7007748s - W32TmServiceMain: resync req, irreg now
pending.
> 147146 16:39:17.7007748s - W32TmServiceMain: waiting i0.000s (70.081s)
> 147146 16:39:17.7163992s - W32TmServiceMain: timeout
> 147146 16:39:17.7163992s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: *STALE*
> 147146 16:39:17.7163992s - W32TmServiceMain: waiting 70.066s
> 147146 16:40:11.9018184s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 16:40:20.7139800s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +00.0004218s
> 147146 16:40:20.7139800s - W32TmServiceMain: resync req, reg too soon.
> 147146 16:40:20.7139800s - W32TmServiceMain: waiting 7.068s
> 147146 16:40:27.7918332s - W32TmServiceMain: timeout
> 147146 16:40:27.7918332s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: SO:4218 KPhO:-678 PhO:4896 uT:8193 FllPPE:4896
PllPPE:4896 FllPPrE:171138 PllPPrE:134543 FllRA:0 PllRA:0 RA:0 CR:156244
nT:453 RAPhO:0 SD:543157 LI:0 S:3 RDl:-44320 RDs:520033 TSF:0x0
Hold(4)->Sync
> 147146 16:40:27.7918332s - W32TmServiceMain: waiting 128.000s
> 147146 16:41:15.9153372s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 16:41:26.7117976s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +00.0043066s
> 147146 16:41:26.7117976s - W32TmServiceMain: resync req, irreg now
pending.
> 147146 16:41:26.7117976s - W32TmServiceMain: waiting i0.000s (69.080s)
> 147146 16:41:26.7274220s - W32TmServiceMain: timeout
> 147146 16:41:26.7274220s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: *STALE*
> 147146 16:41:26.7274220s - W32TmServiceMain: waiting 69.064s
> 147146 16:42:19.9441284s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 16:42:29.7250028s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +00.0005279s
> 147146 16:42:29.7250028s - W32TmServiceMain: resync req, reg too soon.
> 147146 16:42:29.7250028s - W32TmServiceMain: waiting 6.066s
> 147146 16:42:35.8028944s - W32TmServiceMain: timeout
> 147146 16:42:35.8028944s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: *STALE*
> 147146 16:42:35.8028944s - W32TmServiceMain: waiting 128.000s
> 147146 16:43:23.9572952s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 16:43:32.7382080s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: -00.0031741s
> 147146 16:43:32.7382080s - W32TmServiceMain: resync req, irreg now
pending.
> 147146 16:43:32.7382080s - W32TmServiceMain: waiting i0.000s (71.064s)
> 147146 16:43:32.7538324s - W32TmServiceMain: timeout
> 147146 16:43:32.7538324s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: *STALE*
> 147146 16:43:32.7538324s - W32TmServiceMain: waiting 128.000s
> 147146 16:44:27.9704620s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 16:44:36.7357504s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +00.0009323s
> 147146 16:44:36.7357504s - W32TmServiceMain: resync req, irreg now
pending.
> 147146 16:44:36.7357504s - W32TmServiceMain: waiting i0.000s (64.018s)
> 147146 16:44:36.7513748s - W32TmServiceMain: timeout
> 147146 16:44:36.7513748s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: *STALE*
> 147146 16:44:36.7513748s - W32TmServiceMain: waiting 128.000s
> 147146 16:45:31.9836288s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 16:45:40.7489172s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: -00.0027445s
> 147146 16:45:40.7489172s - W32TmServiceMain: resync req, irreg now
pending.
> 147146 16:45:40.7489172s - W32TmServiceMain: waiting i0.000s (64.002s)
> 147146 16:45:40.7645416s - W32TmServiceMain: timeout
> 147146 16:45:40.7645416s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: *STALE*
> 147146 16:45:40.7645416s - W32TmServiceMain: waiting 128.000s
> 147146 16:46:17.1693936s - Computing server digest: OLD:FALSE,
RID:00001976
> 147146 16:46:17.2162668s - TransmitResponse: sent
134.155.55.138:123->134.155.4.35:123
> 147146 16:46:35.9967956s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 16:46:45.7464212s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +00.0015036s
> 147146 16:46:45.7464212s - W32TmServiceMain: resync req, irreg now
pending.
> 147146 16:46:45.7464212s - W32TmServiceMain: waiting i0.000s (63.018s)
> 147146 16:46:45.7620456s - W32TmServiceMain: timeout
> 147146 16:46:45.7620456s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: SO:5279 KPhO:698 PhO:4581 uT:8257 FllPPE:4581
PllPPE:4581 FllPPrE:20098.3 PllPPrE:21038.1 FllRA:0 PllRA:0 RA:0 CR:156244
nT:16387 RAPhO:0 SD:893114 LI:0 S:4 RDl:-28250 RDs:1628172 TSF:0x0 Sync
> 147146 16:46:45.7620456s - W32TmServiceMain: waiting 128.000s
> 147146 16:47:40.0260476s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 16:47:49.7600488s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: -00.0026457s
> 147146 16:47:49.7600488s - W32TmServiceMain: resync req, irreg now
pending.
> 147146 16:47:49.7600488s - W32TmServiceMain: waiting i0.000s (64.001s)
> 147146 16:47:49.7756732s - W32TmServiceMain: timeout
> 147146 16:47:49.7756732s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: SO:-26457 KPhO:671 PhO:-27128 uT:20483 FllPPE:-27128
PllPPE:-27128 FllPPrE:16181.2 PllPPrE:16181.2 FllRA:0 PllRA:0 RA:0 CR:156244
nT:1 RAPhO:0 SD:1146663 (i) LI:0 S:4 RDl:1158 RDs:1642800 TSF:0x0 Sync
> 147146 16:47:49.7756732s - W32TmServiceMain: waiting 63.986s
> 147146 16:48:44.0366352s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 16:48:53.7706364s - W32TmServiceMain: timeout
> 147146 16:48:53.7706364s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: *STALE*
> 147146 16:48:53.7706364s - W32TmServiceMain: waiting 128.000s
> 147146 16:48:55.7549352s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +00.0042118s
> 147146 16:48:55.7549352s - W32TmServiceMain: resync req, irreg now
pending.
> 147146 16:48:55.7549352s - W32TmServiceMain: waiting i14.015s (126.015s)
> 147146 16:49:09.7700220s - W32TmServiceMain: timeout
> 147146 16:49:09.7700220s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: SO:42118 KPhO:-665 PhO:42783 uT:4224 FllPPE:42783
PllPPE:42783 FllPPrE:25550.3 PllPPrE:25550.3 FllRA:2 PllRA:0 RA:1 CR:156245
nT:897 RAPhO:897 SD:1130795 LI:0 S:3 RDl:-127948 RDs:572039 TSF:0x0 Sync
> 147146 16:49:09.7700220s - W32TmServiceMain: waiting 128.000s
> 147146 16:49:48.0542106s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 16:51:05.7861713s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: -00.0075874s
> 147146 16:51:05.7861713s - W32TmServiceMain: resync req, reg too soon.
> 147146 16:51:05.7861713s - W32TmServiceMain: waiting 11.983s
> 147146 16:51:17.7701628s - W32TmServiceMain: timeout
> 147146 16:51:17.7701628s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: SO:-75874 KPhO:647 PhO:-76521 uT:8322 FllPPE:-84843
PllPPE:-68199 FllPPrE:49461.2 PllPPrE:42539.4 FllRA:-2 PllRA:0 RA:0
CR:156245 nT:767 RAPhO:0 SD:1139990 Poll++ LI:0 S:3 RDl:13529 RDs:609858
TSF:0x0 Sync
> 147146 16:51:17.7701628s - TimeProvCommand([NtpClient],
TPC_PollIntervalChanged) called.
> 147146 16:51:17.7701628s - TimeProvCommand([NtpServer],
TPC_PollIntervalChanged) called.
> 147146 16:51:17.7701628s - W32TmServiceMain: waiting 256.000s
> 147146 16:51:17.7701628s - PeerPollingThread: PeerListUpdated
> 147146 16:51:56.0584530s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 16:53:13.7901798s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: -00.0004749s
> 147146 16:53:13.7901798s - W32TmServiceMain: resync req, irreg now
pending.
> 147146 16:53:13.7901798s - W32TmServiceMain: waiting i0.000s (139.979s)
> 147146 16:53:13.8058043s - W32TmServiceMain: timeout
> 147146 16:53:13.8058043s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: *STALE*
> 147146 16:53:13.8058043s - W32TmServiceMain: waiting 139.964s
> 147146 16:54:49.1933768s - Computing server digest: OLD:FALSE,
RID:00001976
> 147146 16:54:49.5683648s - TransmitResponse: sent
134.155.55.138:123->134.155.4.35:123
> 147146 16:55:23.8016443s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: -00.0000266s
> 147146 16:55:23.8016443s - W32TmServiceMain: resync req, reg too soon.
> 147146 16:55:23.8016443s - W32TmServiceMain: waiting 9.968s
> 147146 16:55:33.7700753s - W32TmServiceMain: timeout
> 147146 16:55:33.7700753s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: *STALE*
> 147146 16:55:33.7700753s - W32TmServiceMain: waiting 256.000s
> 147146 16:56:12.0813493s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 16:59:05.2164338s - Computing server digest: OLD:FALSE,
RID:00001976
> 147146 16:59:05.5601728s - TransmitResponse: sent
134.155.55.138:123->134.155.4.35:123
> 147146 16:59:39.8247013s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: -00.0003398s
> 147146 16:59:39.8247013s - W32TmServiceMain: resync req, reg too soon.
> 147146 16:59:39.8247013s - W32TmServiceMain: waiting 9.945s
> 147146 16:59:49.7775078s - W32TmServiceMain: timeout
> 147146 16:59:49.7775078s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: SO:-3398 KPhO:-674 PhO:-2724 uT:32900 FllPPE:63076
PllPPE:-2724 FllPPrE:58615.8 PllPPrE:42499.5 FllRA:0 PllRA:0 RA:0 CR:156245
nT:637 RAPhO:0 SD:879220 LI:0 S:3 RDl:-48006 RDs:578653 TSF:0x0 Sync
> 147146 16:59:49.7775078s - W32TmServiceMain: waiting 256.000s
> 147146 17:00:28.0885066s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 17:00:52.7595921s - TransmitResponse: sent
134.155.55.138:123->134.155.55.191:13999
> 147146 17:01:13.2276871s - Computing server digest: OLD:FALSE,
RID:00001976
> 147146 17:01:13.3058096s - TransmitResponse: sent
134.155.55.138:123->134.155.4.35:123
> 147146 17:01:52.7576721s - TransmitResponse: sent
134.155.55.138:123->134.155.55.191:13999
> 147146 17:02:17.2256391s - Computing server digest: OLD:FALSE,
RID:00001976
> 147146 17:02:17.6006271s - TransmitResponse: sent
134.155.55.138:123->134.155.4.35:123
> 147146 17:02:49.2402396s - Computing server digest: OLD:FALSE,
RID:00001976
> 147146 17:02:49.5058561s - TransmitResponse: sent
134.155.55.138:123->134.155.4.35:123
> 147146 17:02:52.7557521s - TransmitResponse: sent
134.155.55.138:123->134.155.55.191:13999
> 147146 17:03:05.2397276s - Computing server digest: OLD:FALSE,
RID:00001976
> 147146 17:03:05.3178501s - TransmitResponse: sent
134.155.55.138:123->134.155.4.35:123
> 147146 17:03:52.7538321s - TransmitResponse: sent
134.155.55.138:123->134.155.55.191:13999
> 147146 17:03:57.8474191s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: -00.0009773s
> 147146 17:03:57.8474191s - W32TmServiceMain: resync req, reg too soon.
> 147146 17:03:57.8474191s - W32TmServiceMain: waiting 7.930s
> 147146 17:04:05.7846651s - W32TmServiceMain: timeout
> 147146 17:04:05.7846651s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: *STALE*
> 147146 17:04:05.7846651s - W32TmServiceMain: waiting 256.000s
> 147146 17:04:44.0959391s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 17:04:52.7519121s - TransmitResponse: sent
134.155.55.138:123->134.155.55.191:13999
> 147146 17:05:52.7499921s - TransmitResponse: sent
134.155.55.138:123->134.155.55.191:13999
> 147146 17:06:52.7480721s - TransmitResponse: sent
134.155.55.138:123->134.155.55.191:13999
> 147146 17:08:13.8861006s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: -00.0093708s
> 147146 17:08:13.8861006s - W32TmServiceMain: resync req, reg too soon.
> 147146 17:08:13.8861006s - W32TmServiceMain: waiting 7.898s
> 147146 17:08:21.7920976s - W32TmServiceMain: timeout
> 147146 17:08:21.7920976s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: *STALE*
> 147146 17:08:21.7920976s - W32TmServiceMain: waiting 256.000s
> 147146 17:09:00.1033716s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 17:12:29.9091576s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: -00.0102016s
> 147146 17:12:29.9091576s - W32TmServiceMain: resync req, reg too soon.
> 147146 17:12:29.9091576s - W32TmServiceMain: waiting 7.882s
> 147146 17:12:37.7995301s - W32TmServiceMain: timeout
> 147146 17:12:37.7995301s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: *STALE*
> 147146 17:12:37.7995301s - W32TmServiceMain: waiting 256.000s
> 147146 17:13:16.1108041s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 17:16:46.9321826s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: -00.0107408s
> 147146 17:16:46.9321826s - W32TmServiceMain: resync req, reg too soon.
> 147146 17:16:46.9321826s - W32TmServiceMain: waiting 6.867s
> 147146 17:16:53.8069626s - W32TmServiceMain: timeout
> 147146 17:16:53.8069626s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: SO:-107408 KPhO:-646 PhO:-106762 uT:65737
FllPPE:-106762 PllPPE:-106762 FllPPrE:78111.2 PllPPrE:66871.2 FllRA:0
PllRA:-10 RA:-5 CR:156240 nT:440 RAPhO:-2200 SD:487872 LI:0 S:3 RDl:-58733
RDs:596858 TSF:0x0 Sync
> 147146 17:16:53.8069626s - W32TmServiceMain: waiting 256.000s
> 147146 17:17:32.1219830s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 17:18:05.3696138s - Computing server digest: OLD:FALSE,
RID:00001976
> 147146 17:18:05.4477338s - TransmitResponse: sent
134.155.55.138:123->134.155.4.35:123
> 147146 17:21:04.9362458s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +00.0039606s
> 147146 17:21:04.9362458s - W32TmServiceMain: resync req, reg too soon.
> 147146 17:21:04.9362458s - W32TmServiceMain: waiting 4.870s
> 147146 17:21:09.8109338s - W32TmServiceMain: timeout
> 147146 17:21:09.8109338s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: *STALE*
> 147146 17:21:09.8109338s - W32TmServiceMain: waiting 256.000s
> 147146 17:21:48.1366058s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 17:25:19.9667978s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +00.0037767s
> 147146 17:25:19.9667978s - W32TmServiceMain: resync req, reg too soon.
> 147146 17:25:19.9667978s - W32TmServiceMain: waiting 5.844s
> 147146 17:25:25.8257978s - W32TmServiceMain: timeout
> 147146 17:25:25.8257978s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: SO:37767 KPhO:-680 PhO:38447 uT:32837 FllPPE:-125738
PllPPE:202632 FllPPrE:97960.6 PllPPrE:119495 FllRA:0 PllRA:1 RA:0 CR:156240
nT:375 RAPhO:0 SD:500585 LI:0 S:3 RDl:-50978 RDs:622576 TSF:0x0 Sync
> 147146 17:25:25.8257978s - W32TmServiceMain: waiting 256.000s
> 147146 17:26:04.1551199s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 17:26:37.4030538s - Computing server digest: OLD:FALSE,
RID:00001976
> 147146 17:26:37.4811738s - TransmitResponse: sent
134.155.55.138:123->134.155.4.35:123
> 147146 17:27:06.1512138s - Computing server digest: OLD:FALSE,
RID:00001976
> 147146 17:27:06.4168218s - TransmitResponse: sent
134.155.55.138:123->134.155.4.35:123
> 147146 17:29:41.8287498s - W32TmServiceMain: timeout
> 147146 17:29:41.8287498s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
> ClockDispln Update: *STALE*
> 147146 17:29:41.8287498s - W32TmServiceMain: waiting 256.000s
> 147146 17:30:20.1700458s - Polling peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123)
> 147146 17:30:25.9821738s - Response from peer rumtime4.uni-mannheim.de
(ntp.m|0x0|134.155.55.138:123->134.155.51.118:123), ofs: +00.0142532s
> 147146 17:30:25.9821738s - PeerPollingThread: PeerListUpdated
> 147146 17:30:25.9821738s - W32TmServiceMain: resync req, irreg now
pending.
> 147146 17:30:25.9821738s - W32TmServiceMain: waiting i0.000s (211.846s)
> 147146 17:30:25.9977978s - W32TmServiceMain: timeout
> 147146 17:30:25.9977978s - TimeProvCommand([NtpClient], TPC_GetSamples)
called.
>

Hi,

I just stumbled into this thread ...

Yi Zhao [MS] wrote:

> The DC is both client and server for time sync. It runs in symmetric
> active mode in W2K3 by default. There is a known issue with some UNIX
> implementations that have trouble to respond symmetric-active mode
> request. I am not sure if this is one since more detailed log is needed,
> but I will give it a try first.

Please let me not that this is _NOT_ a problem of the NTP implementation as
usually run under Unix, but a violation of the NTP protocol as implemented
in W32time.

Please see also the discussions in comp.protocols.time.ntp

> Windows Time Service can force a Client mode request to solve the problem
> by adding special flag ",0x8" when set the manual peer, such as
> "time.windows.com,0x8".

That's an interesting workaround, though.


Best regards,

Martin

--

Martin Burnicki

Meinberg Funkuhren
Bad Pyrmont
Germany

Martin Burnicki wrote:

> Hi,
>
> I just stumbled into this thread ...
>
> Yi Zhao [MS] wrote:
>
>
>>The DC is both client and server for time sync. It runs in symmetric
>>active mode in W2K3 by default. There is a known issue with some UNIX
>>implementations that have trouble to respond symmetric-active mode
>>request. I am not sure if this is one since more detailed log is needed,
>>but I will give it a try first.
>
>
> Please let me not that this is _NOT_ a problem of the NTP implementation as
> usually run under Unix, but a violation of the NTP protocol as implemented
> in W32time.
>
> Please see also the discussions in comp.protocols.time.ntp
>
>
>>Windows Time Service can force a Client mode request to solve the problem
>>by adding special flag ",0x8" when set the manual peer, such as
>>"time.windows.com,0x8".
>
>
> That's an interesting workaround, though.

But does not work, at least in my case.
>
>
> Best regards,
>
> Martin
>
> --
>
> Martin Burnicki
>
> Meinberg Funkuhren
> Bad Pyrmont
> Germany
>

Stefan Holland wrote:
> Martin Burnicki wrote:
>
>> Hi,
>>
>> I just stumbled into this thread ...
>>
>> Yi Zhao [MS] wrote:
>>
>>
>>>The DC is both client and server for time sync. It runs in symmetric
>>>active mode in W2K3 by default. There is a known issue with some UNIX
>>>implementations that have trouble to respond symmetric-active mode
>>>request. I am not sure if this is one since more detailed log is needed,
>>>but I will give it a try first.
>>
>>
>> Please let me not that this is _NOT_ a problem of the NTP implementation
>> as usually run under Unix, but a violation of the NTP protocol as
>> implemented in W32time.
>>
>> Please see also the discussions in comp.protocols.time.ntp
>>
>>
>>>Windows Time Service can force a Client mode request to solve the problem
>>>by adding special flag ",0x8" when set the manual peer, such as
>>>"time.windows.com,0x8".
>>
>>
>> That's an interesting workaround, though.
>
> But does not work, at least in my case.

I've just verified (using tcpdump under Linux) that by default a Win server
2003 properly sends "client" type requests to a NTP server, which responds
using "server" type messages. That's exacly how it should be and seems to
have been fixed in Win server 2003. So the flag 0x8 seems to be obsolete
under Win server 2003.

The problem mentioned in earlier postings was in w32time shipped with Win XP
where w32time sent "active peer" requests to the NTP server which means
that w32time is willing to "adjust" the NTP server's time. Fortunally, most
versions of the original NTP implementation didn't accept that "offer".
Maybe this misbehaviour of w32time under Win XP can be corrected using the
0x8 flag. I haven't verified that, though.

The problem you're observing seems to occur only if the Win server 2003 is a
PDC. My machine I've mentioned above isn't, so it works as it should.

I've found two threads in German newsgroups where the same problem has been
discussed recently, and it seems the solutions mentioned there may work. I
haven't verified that either, yet. I think you talk German ;-) so here are
the links:
http://groups.google.de/groups?hl=de...TNGP09.phx.gbl
http://groups.google.de/groups?hl=de...TNGP10.phx.gbl

Hope this helps.


Best regards,

Martin

--

Martin Burnicki

Meinberg Funkuhren
Bad Pyrmont
Germany