W32tm syncing problems

[MarcWollner]

We're trying to get a synced domain environment to work but the w32tm syncing doesnt work and we dont know anything what to to.

We tried most of the usual problems we had.

dc1 is running on hyperV but timesync disabled

client is win10 pro

attached the Log of one of our testing clients and th main DC

DC:
152705 15:54:38.3111840s - ---------- Log File Opened -----------------
152705 15:54:38.3114744s - RPC Call - Query Configuration
152705 15:54:38.3115763s - RPC Call - Query Provider Configuration
152705 15:54:38.3116684s - TimeProvCommand([NtpClient], TPC_Query) called.
152705 15:54:38.3117570s - RPC Call - Query Provider Configuration
152705 15:54:38.3118320s - TimeProvCommand([NtpServer], TPC_Query) called.
152705 15:54:38.3127971s - RPC Call - Query Provider Configuration
152705 15:54:41.5448025s - RPC Caller is xxxxxxxx\Administrator (S-xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx)
152705 15:54:41.5449418s - RPC Call Attribute is local=1, kernel=0, session=0, authentication=6, protocol=2, OpNum=0
152705 15:54:41.5450400s - RPC Call - HardResync
152705 15:54:41.5451107s - W32TmServiceMain: ********** Time Slip Notification **********
152705 15:54:41.5452310s - ClockDispln TimeSlip:TimeSlip LastUTC:114711 SetUnsync: LI:0 S:1 RDl:0 RDs:100000000 TSF:0x0
152705 15:54:41.5458035s - ClockDispln: we're a reliable time service with no time source: LS: 0, : 864000000000, WAIT: 86400000
152705 15:54:41.5458646s - TimeProvCommand([NtpClient], TPC_TimeJumped) called.
152705 15:54:41.5459120s - TimeProvCommand([NtpServer], TPC_TimeJumped) called.
152705 15:54:41.5459685s - UpdateTimerQueue1: TN:17923590000::: LRT:8823590000 LUT:17923590000 LAFLTNS:0 TSLGT:0 LTNS:864000000000
152705 15:54:41.5460094s - W32TmServiceMain: waiting i16.000s (64.000s)
152705 15:54:41.5460798s - PeerPollingThread: PeerListUpdated
152705 15:54:41.5461394s - Resolving manual peer: pool.ntp.org,0x8
152705 15:54:41.5607141s - Create new peer associations: #4
152705 15:54:41.5607897s - Association: (Local) 0.0.0.0:123 => 94.199.173.123:123 (Remote)
152705 15:54:41.5608451s - Association: (Local) 0.0.0.0:123 => 212.69.166.153:123 (Remote)
152705 15:54:41.5608957s - Association: (Local) 0.0.0.0:123 => 217.196.145.42:123 (Remote)
152705 15:54:41.5609450s - Association: (Local) 0.0.0.0:123 => 83.137.41.12:123 (Remote)
152705 15:54:41.5609958s - Created reachability group: (
152705 15:54:41.5611850s - 94.199.173.123:123,
152705 15:54:41.5612438s - 212.69.166.153:123,
152705 15:54:41.5612864s - 217.196.145.42:123,
152705 15:54:41.5613268s - 83.137.41.12:123,
152705 15:54:41.5613615s - )
152705 15:54:41.5614274s - PollIntervalChange(): clear: host 0 = max 15
152705 15:54:41.5614854s - PollIntervalChange(): clear: host 0 = max 15
152705 15:54:41.5615362s - PollIntervalChange(): clear: host 0 = max 15
152705 15:54:41.5615860s - PollIntervalChange(): clear: host 0 = max 15
152705 15:54:41.5616436s - PeerPollingThread: waiting 0.000s
152705 15:54:41.5617191s - PeerPollingThread: PeerListUpdated
152705 15:54:41.5617670s - Reachability: Attempting to contact peer pool.ntp.org,0x8 (ntp.m|0x8|0.0.0.0:123->94.199.173.123:123).
152705 15:54:41.5618235s - Polling peer pool.ntp.org,0x8 (ntp.m|0x8|0.0.0.0:123->94.199.173.123:123)
152705 15:54:41.5618655s - Sending packet to pool.ntp.org,0x8 (ntp.m|0x8|0.0.0.0:123->94.199.173.123:123) in Win2K detect mode, stage 1.
152705 15:54:41.5620486s - PollIntervalChange(pool.ntp.org,0x8 (ntp.m|0x8|0.0.0.0:123->94.199.173.123:123)): reclamp: 15 -> 6 (min=4, max=15, sys=6)
152705 15:54:41.5620962s - Peer poll: Max:64.0000000s Cur:00.0000000s
152705 15:54:41.5652736s - Tx timestamp not returned and may be unsupported on the current network interface.
152705 15:54:41.5653590s - PeerPollingThread: waiting 0.100s
152705 15:54:41.5654354s - PeerPollingThread: PeerListUpdated
152705 15:54:41.5654843s - PeerPollingThread: waiting 0.097s
152705 15:54:41.6741984s - PeerPollingThread: WaitTimeout
152705 15:54:41.6743070s - Reachability: Attempting to contact peer pool.ntp.org,0x8 (ntp.m|0x8|0.0.0.0:123->212.69.166.153:123).
152705 15:54:41.6743714s - Polling peer pool.ntp.org,0x8 (ntp.m|0x8|0.0.0.0:123->212.69.166.153:123)
152705 15:54:41.6744141s - Sending packet to pool.ntp.org,0x8 (ntp.m|0x8|0.0.0.0:123->212.69.166.153:123) in Win2K detect mode, stage 1.
152705 15:54:41.6746613s - PollIntervalChange(pool.ntp.org,0x8 (ntp.m|0x8|0.0.0.0:123->212.69.166.153:123)): reclamp: 15 -> 6 (min=4, max=15, sys=6)
152705 15:54:41.6748766s - Peer poll: Max:64.0000000s Cur:00.0000000s
152705 15:54:41.6891866s - Tx timestamp not returned and may be unsupported on the current network interface.
152705 15:54:41.6892611s - PeerPollingThread: waiting 0.100s
152705 15:54:41.6893268s - PeerPollingThread: PeerListUpdated
152705 15:54:41.6893730s - PeerPollingThread: waiting 0.085s
152705 15:54:41.7827855s - PeerPollingThread: WaitTimeout
152705 15:54:41.7828930s - Reachability: Attempting to contact peer pool.ntp.org,0x8 (ntp.m|0x8|0.0.0.0:123->217.196.145.42:123).
152705 15:54:41.7829551s - PeerPollingThread: PeerListUpdated
152705 15:54:41.7830042s - Polling peer pool.ntp.org,0x8 (ntp.m|0x8|0.0.0.0:123->217.196.145.42:123)
152705 15:54:41.7830456s - Sending packet to pool.ntp.org,0x8 (ntp.m|0x8|0.0.0.0:123->217.196.145.42:123) in Win2K detect mode, stage 1.
152705 15:54:41.7830998s - PeerPollingThread: waiting 0.100s
152705 15:54:41.7833420s - PollIntervalChange(pool.ntp.org,0x8 (ntp.m|0x8|0.0.0.0:123->217.196.145.42:123)): reclamp: 15 -> 6 (min=4, max=15, sys=6)
152705 15:54:41.7833921s - Peer poll: Max:64.0000000s Cur:00.0000000s
152705 15:54:41.7983333s - Tx timestamp not returned and may be unsupported on the current network interface.
152705 15:54:41.7984078s - PeerPollingThread: waiting 0.100s
152705 15:54:41.9077416s - PeerPollingThread: WaitTimeout
152705 15:54:41.9078251s - Reachability: Attempting to contact peer pool.ntp.org,0x8 (ntp.m|0x8|0.0.0.0:123->83.137.41.12:123).
152705 15:54:41.9078660s - Polling peer pool.ntp.org,0x8 (ntp.m|0x8|0.0.0.0:123->83.137.41.12:123)
152705 15:54:41.9079050s - Sending packet to pool.ntp.org,0x8 (ntp.m|0x8|0.0.0.0:123->83.137.41.12:123) in Win2K detect mode, stage 1.
152705 15:54:41.9080834s - PollIntervalChange(pool.ntp.org,0x8 (ntp.m|0x8|0.0.0.0:123->83.137.41.12:123)): reclamp: 15 -> 6 (min=4, max=15, sys=6)
152705 15:54:41.9081320s - Peer poll: Max:64.0000000s Cur:00.0000000s
152705 15:54:41.9234796s - Tx timestamp not returned and may be unsupported on the current network interface.
152705 15:54:41.9235646s - PeerPollingThread: waiting 63.654s
152705 15:54:57.5358270s - W32TmServiceMain: timeout
152705 15:54:57.5359362s - Sample Prepared at 131937692975359195 for peer pool.ntp.org,0x8 (ntp.m|0x8|0.0.0.0:123->94.199.173.123:123)
152705 15:54:57.5359808s - Sample Prepared at 131937692975359195 for peer pool.ntp.org,0x8 (ntp.m|0x8|0.0.0.0:123->212.69.166.153:123)
152705 15:54:57.5360188s - Sample Prepared at 131937692975359195 for peer pool.ntp.org,0x8 (ntp.m|0x8|0.0.0.0:123->217.196.145.42:123)
152705 15:54:57.5360568s - Sample Prepared at 131937692975359195 for peer pool.ntp.org,0x8 (ntp.m|0x8|0.0.0.0:123->83.137.41.12:123)
152705 15:54:57.5360937s - ** NTP sample vector is empty.
152705 15:54:57.5361297s - No new NTP sample is available.
152705 15:54:57.5404182s - UpdateTimerQueue1: TN:18083590000::: LRT:8823590000 LUT:17923590000 LAFLTNS:159898805 TSLGT:159898805 LTNS:864000000000
152705 15:54:57.5404764s - W32TmServiceMain: waiting 64.000s

client:

152705 15:54:24.2531038s - ---------- Log File Opened -----------------
152705 15:54:24.2531680s - RPC Call - Query Configuration
152705 15:54:24.2531871s - RPC Call - Query Provider Configuration
152705 15:54:24.2531996s - TimeProvCommand([NtpClient], TPC_Query) called.
152705 15:54:24.2532116s - RPC Call - Query Provider Configuration
152705 15:54:32.5664695s - RPC Caller is xxxxxxxx\xxxxxxxxxxxxxxx (S-xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx)
152705 15:54:32.5666349s - RPC Call Attribute is local=1, kernel=0, session=0, authentication=6, protocol=2, OpNum=0
152705 15:54:32.5666562s - RPC Call - HardResync
152705 15:54:32.5666698s - W32TmServiceMain: ********** Time Slip Notification **********
152705 15:54:32.5666987s - ClockDispln TimeSlip:TimeSlip LastUTC:244882 SetUnsync: LI:3 S:0 RDl:0 RDs:0 TSF:0x0
152705 15:54:32.5667254s - TimeProvCommand([NtpClient], TPC_TimeJumped) called.
152705 15:54:32.5667406s - TimeProvCommand([NtpClient], TPC_PollIntervalChanged) called.
152705 15:54:32.5667525s - UpdateTimerQueue1: TN:38262810000::: LRT:3314370000 LUT:38262810000 LAFLTNS:0 TSLGT:0 LTNS:864000000000
152705 15:54:32.5667585s - W32TmServiceMain: waiting i16.000s (64.000s)
152705 15:54:32.5670140s - PeerPollingThread: PeerListUpdated
152705 15:54:32.5670325s - Resolving domain peer
152705 15:54:32.5670446s - PeerPollingThread: PeerListUpdated
152705 15:54:32.5670616s - PeerPollingThread: waiting forever
152705 15:54:32.6688355s - Domain member syncing from \\DC1.xxxxxxxxx.local.
152705 15:54:32.6692082s - Create new peer associations: #1
152705 15:54:32.6692191s - Association: (Local) 0.0.0.0:123 => [DC1_ip]:123 (Remote)
152705 15:54:32.6692298s - Created reachability group: (
152705 15:54:32.6692357s - [DC1_ip]:123,
152705 15:54:32.6692407s - )
152705 15:54:32.6692529s - PollIntervalChange(): clear: host 0 = max 17
152705 15:54:32.6692576s - PeerPollingThread: PeerListUpdated
152705 15:54:32.6692656s - PeerPollingThread: waiting 0.000s
152705 15:54:32.6692766s - Reachability: Attempting to contact peer DC1.xxxxxxx.local (ntp.d|0.0.0.0:123->[DC1_ip]:123).
152705 15:54:32.6692997s - PeerPollingThread: WaitTimeout
152705 15:54:32.6693134s - Polling peer DC1.xxxxxxxxx.local (ntp.d|0.0.0.0:123->[DC1_ip]:123)
152705 15:54:32.6693219s - Sending packet to DC1.xxxxxxxxxxxxxx.local (ntp.d|0.0.0.0:123->[DC1_ip]:123) in Win2K detect mode, stage 1.
152705 15:54:32.6693293s - PeerPollingThread: waiting forever
152705 15:54:32.6693807s - PollIntervalChange(DC1.xxxxxxxxxx.local (ntp.d|0.0.0.0:123->[DC1_ip]:123)): reclamp: 17 -> 6 (min=4, max=17, sys=6)
152705 15:54:32.6693962s - Peer poll: Max:64.0000000s Cur:00.0000000s
152705 15:54:32.6713044s - Tx timestamp not returned and may be unsupported on the current network interface.
152705 15:54:32.6713137s - PeerPollingThread: waiting 64.000s
152705 15:54:48.5673112s - W32TmServiceMain: timeout
152705 15:54:48.5673612s - Sample Prepared at 131937692885673578 for peer ALC-DC1.ALC-CREW.local (ntp.d|0.0.0.0:123->[DC1_ip]:123)
152705 15:54:48.5673807s - ** NTP sample vector is empty.
152705 15:54:48.5673861s - No new NTP sample is available.
152705 15:54:48.5673963s - UpdateTimerQueue1: TN:38422810000::: LRT:3314370000 LUT:38262810000 LAFLTNS:160000000 TSLGT:160005794 LTNS:864000000000
152705 15:54:48.5674022s - W32TmServiceMain: waiting 64.000s

CMD on DC1

C:\Users\Administrator>w32tm /stripchart /computer:pool.ntp.org
pool.ntp.org wird verfolgt [212.69.166.153:123].
Es ist 04.02.2019 17:18:31.
17:18:31, d:+00.0062602s o:-101.2033604s [@ | ]
17:18:33, d:+00.0095638s o:-101.2019324s [@ | ]
17:18:35, d:+00.0077534s o:-101.2027059s [@ | ]
17:18:37, d:+00.0064315s o:-101.2032386s [@ | ]
17:18:39, d:+00.0072881s o:-101.2032996s [@ | ]
17:18:41, d:+00.0063889s o:-101.2026886s [@ | ]
17:18:43, d:+00.0077701s o:-101.2030232s [@ | ]
17:18:45, d:+00.0155579s o:-101.1986713s [@ | ]

CMD on Client:

C:\WINDOWS\system32>w32tm /stripchart /computer:dc1
dc1 wird verfolgt [[DC1_ip]:123].
Es ist 04.02.2019 17:18:22.
17:18:22, d:+00.0009326s o:-118.1061382s [@ | ]
17:18:24, d:+00.0011027s o:-118.1062870s [@ | ]
17:18:26, d:+00.0011349s o:-118.1062802s [@ | ]
17:18:28, d:+00.0013825s o:-118.1063390s [@ | ]
17:18:30, d:+00.0011674s o:-118.1064799s [@ | ]
17:18:32, d:+00.0017972s o:-118.1060725s [@ | ]
17:18:34, d:+00.0011791s o:-118.1065154s [@ | ]

Thanks for any ideas.