Re: [chrony-dev] SW/HW timestamping on Linux

[Denny Page <dennypage@xxxxxx>]

> On Nov 11, 2016, at 02:17, Miroslav Lichvar <mlichvar@xxxxxxxxxx> wrote:
> 
> The mixed results in tests 3, 4 and 8 are not as expected, however.
> Can you please try running one of those tests with 'acquisitionport
> 10000' in chrony.conf and post the debug output (after ./configure
> --enable-debug) showing few exchanges?
> 
> 
>> test 4
>> - hw stamps on for both igb0 and igb3
>> - all ntp servers enabled
>> - result: H H for one server attached via igb0, mixed H H and D H for other server attached via igb0, mixed H H and D H for server attached via igb3


Miroslav,

Following is output from test 4. Please let me know if this gives you the information that you are looking for.

Thanks,
Denny

---

chrony.conf (partial):
server 192.168.230.240 iburst minpoll 0 maxpoll 0 maxdelaydevratio 4
server 192.168.230.244 iburst minpoll 0 maxpoll 0 maxdelaydevratio 4 noselect
server 192.168.230.245 iburst minpoll 0 maxpoll 0 maxdelaydevratio 4 noselect
hwtimestamp igb0
hwtimestamp igb3
acquisitionport 10000


measurements log:
2016-11-11 17:45:05 192.168.230.244 N  1 111 111 1101   0  4 1.00  1.000e-08  2.560e-05  1.618e-07  0.000e+00  0.000e+00 47505300 4B H H
2016-11-11 17:45:05 192.168.230.240 N  1 111 111 1111   0  4 1.00 -9.500e-08  2.566e-05  1.618e-07  0.000e+00  0.000e+00 47505300 4B H H
2016-11-11 17:45:05 192.168.230.245 N  1 111 111 1111   0  4 1.00  2.142e-06  1.276e-05  1.618e-07  0.000e+00  0.000e+00 47505300 4B H H
2016-11-11 17:45:06 192.168.230.244 N  1 111 111 1101   0  4 1.00  8.163e-06  4.209e-05  1.618e-07  0.000e+00  0.000e+00 47505300 4B D H
2016-11-11 17:45:06 192.168.230.240 N  1 111 111 1111   0  4 1.00 -6.000e-08  2.565e-05  1.618e-07  0.000e+00  0.000e+00 47505300 4B H H
2016-11-11 17:45:06 192.168.230.245 N  1 111 111 1111   0  4 1.00  2.262e-06  1.277e-05  1.618e-07  0.000e+00  0.000e+00 47505300 4B H H
2016-11-11 17:45:07 192.168.230.244 N  1 111 111 1101   0  4 1.00 -1.700e-08  2.556e-05  1.618e-07  0.000e+00  0.000e+00 47505300 4B H H
2016-11-11 17:45:07 192.168.230.240 N  1 111 111 1111   0  4 1.00  5.200e-08  2.562e-05  1.618e-07  0.000e+00  0.000e+00 47505300 4B H H
2016-11-11 17:45:07 192.168.230.245 N  1 111 111 1101   0  4 1.00  1.103e-05  3.105e-05  1.618e-07  0.000e+00  0.000e+00 47505300 4B D H
2016-11-11 17:45:08 192.168.230.244 N  1 111 111 1101   0  4 1.00  1.400e-07  2.572e-05  1.618e-07  0.000e+00  0.000e+00 47505300 4B H H
2016-11-11 17:45:08 192.168.230.240 N  1 111 111 1111   0  4 1.00 -1.280e-07  2.562e-05  1.618e-07  0.000e+00  0.000e+00 47505300 4B H H
2016-11-11 17:45:08 192.168.230.245 N  1 111 111 1111   0  4 1.00  2.123e-06  1.273e-05  1.618e-07  0.000e+00  0.000e+00 47505300 4B H H
2016-11-11 17:45:09 192.168.230.244 N  1 111 111 1101   0  4 1.00 -7.800e-08  2.564e-05  1.618e-07  0.000e+00  0.000e+00 47505300 4B H H
2016-11-11 17:45:09 192.168.230.240 N  1 111 111 1111   0  4 1.00 -6.000e-08  2.564e-05  1.618e-07  0.000e+00  0.000e+00 47505300 4B H H
2016-11-11 17:45:09 192.168.230.245 N  1 111 111 1101   0  4 1.00  1.188e-05  3.229e-05  1.618e-07  0.000e+00  0.000e+00 47505300 4B D H


debug output (command line optiosn -f /etc/chrony/chrony.conf -P 9 -s -r -d -d):
2016-11-11T17:45:05Z ntp_core.c:1032:(transmit_timeout) Transmit timeout for [192.168.230.244:123]
2016-11-11T17:45:05Z ntp_io.c:819:(NIO_SendPacket) Sent 48 bytes to 192.168.230.244:123 from [UNSPEC] fd 8
2016-11-11T17:45:05Z ntp_io_linux.c:444:(NIO_Linux_ProcessMessage) Received 48 bytes from error queue for 192.168.230.244:123 fd=8 if=2 tss=2
2016-11-11T17:45:05Z ntp_core.c:1888:(update_tx_timestamp) Updated TX timestamp delay=0.000016623
2016-11-11T17:45:05Z ntp_io.c:657:(process_message) Received 48 bytes from 192.168.230.244:123 to 192.168.230.3 fd=8 if=2 tss=2 delay=0.000070806
2016-11-11T17:45:05Z sourcestats.c:808:(SST_IsGoodSample) Bad sample: offset=0.000006 delay=0.000026 incr_delay=0.000002 allowed=0.000006
2016-11-11T17:45:05Z ntp_core.c:1484:(receive_packet) NTP packet lvm=44 stratum=1 poll=4 prec=-25 root_delay=0.000000 root_disp=0.000000 refid=47505300 []
2016-11-11T17:45:05Z ntp_core.c:1489:(receive_packet) reference=1478886305.000000000 origin=4099013356.361615043 receive=1478886305.070201480 transmit=1478886305.070206240
2016-11-11T17:45:05Z ntp_core.c:1491:(receive_packet) offset=0.000000010 delay=0.000025600 dispersion=0.000000 root_delay=0.000026 root_dispersion=0.000000
2016-11-11T17:45:05Z ntp_core.c:1495:(receive_packet) test123=111 test567=111 testABCD=1101 kod_rate=0 interleaved=0 valid=1 good=0 updated=1
2016-11-11T17:45:05Z ntp_io.c:657:(process_message) Received 48 bytes from 192.168.225.10:123 to 192.168.230.3 fd=10 if=2 tss=2 delay=0.000029434
2016-11-11T17:45:05Z clientlog.c:447:(CLG_LogNTPAccess) NTP hits 24 rate -4 trate -128 tokens 0
2016-11-11T17:45:05Z ntp_io.c:819:(NIO_SendPacket) Sent 48 bytes to 192.168.225.10:123 from 192.168.230.3 fd 10
2016-11-11T17:45:05Z ntp_io_linux.c:444:(NIO_Linux_ProcessMessage) Received 48 bytes from error queue for 192.168.225.10:123 fd=10 if=2 tss=2
2016-11-11T17:45:05Z ntp_core.c:1882:(update_tx_timestamp) Unacceptable TX delay -607092190.806053162
2016-11-11T17:45:05Z ntp_core.c:1032:(transmit_timeout) Transmit timeout for [192.168.230.240:123]
2016-11-11T17:45:05Z ntp_io.c:819:(NIO_SendPacket) Sent 48 bytes to 192.168.230.240:123 from [UNSPEC] fd 8
2016-11-11T17:45:05Z hwclock.c:180:(HCL_AccumulateSample) HW clock samples=16 offset=3.788998e-08 freq=1.000027278e+00 raw_freq=9.999993804e-01 err=1.032000e-06 ref_diff=2.780381e+01
2016-11-11T17:45:05Z ntp_io_linux.c:444:(NIO_Linux_ProcessMessage) Received 48 bytes from error queue for 192.168.230.240:123 fd=8 if=2 tss=2
2016-11-11T17:45:05Z ntp_core.c:1888:(update_tx_timestamp) Updated TX timestamp delay=0.000015766
2016-11-11T17:45:05Z ntp_io.c:657:(process_message) Received 48 bytes from 192.168.230.240:123 to 192.168.230.3 fd=8 if=2 tss=2 delay=0.000140798
2016-11-11T17:45:05Z ntp_core.c:1484:(receive_packet) NTP packet lvm=44 stratum=1 poll=4 prec=-25 root_delay=0.000000 root_disp=0.000000 refid=47505300 []
2016-11-11T17:45:05Z ntp_core.c:1489:(receive_packet) reference=1478886305.000000000 origin=2879420439.762509423 receive=1478886305.620853316 transmit=1478886305.620858000
2016-11-11T17:45:05Z ntp_core.c:1491:(receive_packet) offset=-0.000000095 delay=0.000025659 dispersion=0.000000 root_delay=0.000026 root_dispersion=0.000000
2016-11-11T17:45:05Z ntp_core.c:1495:(receive_packet) test123=111 test567=111 testABCD=1111 kod_rate=0 interleaved=0 valid=1 good=1 updated=1
2016-11-11T17:45:05Z sources.c:360:(SRC_AccumulateSample) ip=[192.168.230.240] t=1478886305.620855753 ofs=0.000000 del=0.000026 disp=0.000000 str=1
2016-11-11T17:45:05Z sourcestats.c:550:(SST_DoNewRegression) off=1.923832e-08 freq=2.120460e-09 skew=2.321352e-08 n=19 bs=0 runs=10 asym=0.000000 arun=0
2016-11-11T17:45:05Z sourcestats.c:657:(SST_GetSelectionData) n=19 off=0.000000 dist=0.000013 var=0.000000 first_ago=18.169812 last_ago=0.000156 selok=1
2016-11-11T17:45:05Z sources.c:770:(SRC_SelectSource) badstat=0 sel=1 badstat_reach=0 sel_reach=ff max_reach_ago=18.169812
2016-11-11T17:45:05Z sources.c:1006:(SRC_SelectSource) select score=1.000000 refid=c0a8e6f0 match_refid=c0a8e6f0 status=0 dist=0.000113
2016-11-11T17:45:05Z sourcestats.c:687:(SST_GetTrackingData) n=19 freq=0.000000 (0.002ppm) skew=0.000000 (0.023ppm) avoff=0.000000 offsd=0.000000 disp=0.000000
2016-11-11T17:45:05Z local.c:615:(LCL_AccumulateFrequencyAndOffset) old_freq=27.896ppm new_freq=27.898ppm offset=0.000000sec
2016-11-11T17:45:05Z sys_generic.c:251:(update_slew) slew offset=-1.220063e-08 corr_rate=7.008645e-08 base_freq=27.898012 total_freq=27.895874 slew_freq=-2.137912e-09 duration=5.706799 slew_error=1.564388e-11
2016-11-11T17:45:05Z sys_generic.c:251:(update_slew) slew offset=7.038843e-09 corr_rate=6.896411e-08 base_freq=27.898012 total_freq=27.898727 slew_freq=7.155616e-10 duration=9.836810 slew_error=3.138820e-11
2016-11-11T17:45:05Z rtc_linux.c:295:(slew_samples) dfreq=0.00000000 doffset=0.000000 old_fast=-0.993865 old_rate=-95.966 new_fast=-0.993865 new_rate=-95.966
2016-11-11T17:45:05Z sourcestats.c:725:(SST_SlewSamples) n=19 m=16 old_off_time=1478886305.620855753 new=1478886305.620855733 old_off=0.000000 new_off=-0.000000 old_freq=0.002 new_freq=0.000
2016-11-11T17:45:05Z sourcestats.c:725:(SST_SlewSamples) n=16 m=16 old_off_time=1478886304.058275254 new=1478886304.058275237 old_off=-0.000006 new_off=-0.000006 old_freq=0.002 new_freq=0.000
2016-11-11T17:45:05Z sourcestats.c:725:(SST_SlewSamples) n=17 m=17 old_off_time=1478886304.836750205 new=1478886304.836750187 old_off=-0.000002 new_off=-0.000002 old_freq=0.003 new_freq=0.002
2016-11-11T17:45:05Z ntp_core.c:1032:(transmit_timeout) Transmit timeout for [192.168.230.245:123]
2016-11-11T17:45:05Z ntp_io.c:819:(NIO_SendPacket) Sent 48 bytes to 192.168.230.245:123 from [UNSPEC] fd 8
2016-11-11T17:45:05Z hwclock.c:180:(HCL_AccumulateSample) HW clock samples=16 offset=-2.954276e-08 freq=1.000027271e+00 raw_freq=9.999993725e-01 err=1.032000e-06 ref_diff=1.608740e+00
2016-11-11T17:45:05Z ntp_io_linux.c:444:(NIO_Linux_ProcessMessage) Received 48 bytes from error queue for 192.168.230.245:123 fd=8 if=5 tss=2
2016-11-11T17:45:05Z ntp_core.c:1888:(update_tx_timestamp) Updated TX timestamp delay=0.000018001
2016-11-11T17:45:05Z ntp_io.c:657:(process_message) Received 48 bytes from 192.168.230.245:123 to 192.168.230.94 fd=8 if=5 tss=2 delay=0.000142038
2016-11-11T17:45:05Z ntp_core.c:1484:(receive_packet) NTP packet lvm=44 stratum=1 poll=4 prec=-25 root_delay=0.000000 root_disp=0.000000 refid=47505300 []
2016-11-11T17:45:05Z ntp_core.c:1489:(receive_packet) reference=1478886305.000000000 origin=1529284683.046999417 receive=1478886305.850412137 transmit=1478886305.850416897
2016-11-11T17:45:05Z ntp_core.c:1491:(receive_packet) offset=0.000002142 delay=0.000012760 dispersion=0.000000 root_delay=0.000013 root_dispersion=0.000000
2016-11-11T17:45:05Z ntp_core.c:1495:(receive_packet) test123=111 test567=111 testABCD=1111 kod_rate=0 interleaved=0 valid=1 good=1 updated=1
2016-11-11T17:45:05Z sources.c:360:(SRC_AccumulateSample) ip=[192.168.230.245] t=1478886305.850412375 ofs=-0.000002 del=0.000013 disp=0.000000 str=1
2016-11-11T17:45:05Z sourcestats.c:550:(SST_DoNewRegression) off=-2.131442e-06 freq=2.048259e-09 skew=2.388979e-08 n=18 bs=0 runs=10 asym=0.000000 arun=2
2016-11-11T17:45:05Z sourcestats.c:657:(SST_GetSelectionData) n=19 off=0.000000 dist=0.000013 var=0.000000 first_ago=18.399364 last_ago=0.229707 selok=1
2016-11-11T17:45:05Z sources.c:770:(SRC_SelectSource) badstat=0 sel=1 badstat_reach=0 sel_reach=ff max_reach_ago=18.399364
2016-11-11T17:45:05Z sources.c:1006:(SRC_SelectSource) select score=1.000000 refid=c0a8e6f0 match_refid=c0a8e6f5 status=0 dist=0.000113
2016-11-11T17:45:06Z ntp_core.c:1032:(transmit_timeout) Transmit timeout for [192.168.230.244:123]
2016-11-11T17:45:06Z ntp_io.c:819:(NIO_SendPacket) Sent 48 bytes to 192.168.230.244:123 from [UNSPEC] fd 8
2016-11-11T17:45:06Z ntp_io.c:657:(process_message) Received 48 bytes from 192.168.230.244:123 to 192.168.230.3 fd=8 if=2 tss=2 delay=0.000022516
2016-11-11T17:45:06Z sourcestats.c:808:(SST_IsGoodSample) Bad sample: offset=-0.000002 delay=0.000042 incr_delay=0.000003 allowed=0.000014
2016-11-11T17:45:06Z ntp_core.c:1484:(receive_packet) NTP packet lvm=44 stratum=1 poll=4 prec=-25 root_delay=0.000000 root_disp=0.000000 refid=47505300 []
2016-11-11T17:45:06Z ntp_core.c:1489:(receive_packet) reference=1478886306.000000000 origin=882400267.756283207 receive=1478886306.082181777 transmit=1478886306.082186496
2016-11-11T17:45:06Z ntp_core.c:1491:(receive_packet) offset=0.000008163 delay=0.000042087 dispersion=0.000000 root_delay=0.000042 root_dispersion=0.000000
2016-11-11T17:45:06Z ntp_core.c:1495:(receive_packet) test123=111 test567=111 testABCD=1101 kod_rate=0 interleaved=0 valid=1 good=0 updated=1
2016-11-11T17:45:06Z ntp_io_linux.c:444:(NIO_Linux_ProcessMessage) Received 48 bytes from error queue for 192.168.230.244:123 fd=8 if=2 tss=2
2016-11-11T17:45:06Z ntp_core.c:1888:(update_tx_timestamp) Updated TX timestamp delay=0.000016447
2016-11-11T17:45:06Z ntp_io.c:657:(process_message) Received 48 bytes from 192.168.230.2:123 to 192.168.230.3 fd=10 if=2 tss=2 delay=0.000029965
2016-11-11T17:45:06Z clientlog.c:447:(CLG_LogNTPAccess) NTP hits 24 rate -4 trate -128 tokens 0
2016-11-11T17:45:06Z ntp_io.c:819:(NIO_SendPacket) Sent 48 bytes to 192.168.230.2:123 from 192.168.230.3 fd 10
2016-11-11T17:45:06Z ntp_io_linux.c:444:(NIO_Linux_ProcessMessage) Received 48 bytes from error queue for 192.168.230.2:123 fd=10 if=2 tss=2
2016-11-11T17:45:06Z ntp_core.c:1882:(update_tx_timestamp) Unacceptable TX delay -607092189.598368764
2016-11-11T17:45:06Z ntp_core.c:1032:(transmit_timeout) Transmit timeout for [192.168.230.240:123]
2016-11-11T17:45:06Z ntp_io.c:819:(NIO_SendPacket) Sent 48 bytes to 192.168.230.240:123 from [UNSPEC] fd 8
2016-11-11T17:45:06Z hwclock.c:180:(HCL_AccumulateSample) HW clock samples=15 offset=3.657525e-08 freq=1.000027280e+00 raw_freq=9.999993810e-01 err=1.032000e-06 ref_diff=2.780384e+01
2016-11-11T17:45:06Z ntp_io_linux.c:444:(NIO_Linux_ProcessMessage) Received 48 bytes from error queue for 192.168.230.240:123 fd=8 if=2 tss=2
2016-11-11T17:45:06Z ntp_core.c:1888:(update_tx_timestamp) Updated TX timestamp delay=0.000015840
2016-11-11T17:45:06Z ntp_io.c:657:(process_message) Received 48 bytes from 192.168.230.240:123 to 192.168.230.3 fd=8 if=2 tss=2 delay=0.000140344
2016-11-11T17:45:06Z ntp_core.c:1484:(receive_packet) NTP packet lvm=44 stratum=1 poll=4 prec=-25 root_delay=0.000000 root_disp=0.000000 refid=47505300 []
2016-11-11T17:45:06Z ntp_core.c:1489:(receive_packet) reference=1478886306.000000000 origin=969486176.251124781 receive=1478886306.622055431 transmit=1478886306.622060232
2016-11-11T17:45:06Z ntp_core.c:1491:(receive_packet) offset=-0.000000060 delay=0.000025646 dispersion=0.000000 root_delay=0.000026 root_dispersion=0.000000
2016-11-11T17:45:06Z ntp_core.c:1495:(receive_packet) test123=111 test567=111 testABCD=1111 kod_rate=0 interleaved=0 valid=1 good=1 updated=1
2016-11-11T17:45:06Z sources.c:360:(SRC_AccumulateSample) ip=[192.168.230.240] t=1478886306.622057891 ofs=0.000000 del=0.000026 disp=0.000000 str=1
2016-11-11T17:45:06Z sourcestats.c:550:(SST_DoNewRegression) off=1.366035e-08 freq=1.780994e-09 skew=2.054309e-08 n=20 bs=0 runs=10 asym=0.000000 arun=0
2016-11-11T17:45:06Z sourcestats.c:657:(SST_GetSelectionData) n=20 off=0.000000 dist=0.000013 var=0.000000 first_ago=19.171014 last_ago=0.000156 selok=1
2016-11-11T17:45:06Z sources.c:770:(SRC_SelectSource) badstat=0 sel=1 badstat_reach=0 sel_reach=ff max_reach_ago=19.171014
2016-11-11T17:45:06Z sources.c:1006:(SRC_SelectSource) select score=1.000000 refid=c0a8e6f0 match_refid=c0a8e6f0 status=0 dist=0.000113
2016-11-11T17:45:06Z sourcestats.c:687:(SST_GetTrackingData) n=20 freq=0.000000 (0.002ppm) skew=0.000000 (0.021ppm) avoff=0.000000 offsd=0.000000 disp=0.000000
2016-11-11T17:45:06Z local.c:615:(LCL_AccumulateFrequencyAndOffset) old_freq=27.898ppm new_freq=27.899ppm offset=0.000000sec
2016-11-11T17:45:06Z sys_generic.c:251:(update_slew) slew offset=6.322446e-09 corr_rate=6.896411e-08 base_freq=27.899460 total_freq=27.900040 slew_freq=5.795010e-10 duration=10.910156 slew_error=1.496667e-12
2016-11-11T17:45:06Z sys_generic.c:251:(update_slew) slew offset=1.998368e-08 corr_rate=6.585593e-08 base_freq=27.899460 total_freq=27.905518 slew_freq=6.057559e-09 duration=3.298966 slew_error=6.025864e-11
2016-11-11T17:45:06Z rtc_linux.c:295:(slew_samples) dfreq=0.00000000 doffset=0.000000 old_fast=-0.993865 old_rate=-95.966 new_fast=-0.993865 new_rate=-95.966
2016-11-11T17:45:06Z sourcestats.c:725:(SST_SlewSamples) n=20 m=16 old_off_time=1478886306.622057891 new=1478886306.622057877 old_off=0.000000 new_off=-0.000000 old_freq=0.002 new_freq=0.000
2016-11-11T17:45:06Z sourcestats.c:725:(SST_SlewSamples) n=16 m=16 old_off_time=1478886304.058275237 new=1478886304.058275227 old_off=-0.000006 new_off=-0.000006 old_freq=0.000 new_freq=-0.001
2016-11-11T17:45:06Z sourcestats.c:725:(SST_SlewSamples) n=18 m=17 old_off_time=1478886305.850412375 new=1478886305.850412362 old_off=-0.000002 new_off=-0.000002 old_freq=0.002 new_freq=0.001
2016-11-11T17:45:06Z ntp_core.c:1032:(transmit_timeout) Transmit timeout for [192.168.230.245:123]
2016-11-11T17:45:06Z ntp_io.c:819:(NIO_SendPacket) Sent 48 bytes to 192.168.230.245:123 from [UNSPEC] fd 8
2016-11-11T17:45:06Z hwclock.c:180:(HCL_AccumulateSample) HW clock samples=16 offset=-5.368928e-08 freq=1.000027276e+00 raw_freq=9.999993759e-01 err=1.032000e-06 ref_diff=1.608767e+00
2016-11-11T17:45:06Z ntp_io_linux.c:444:(NIO_Linux_ProcessMessage) Received 48 bytes from error queue for 192.168.230.245:123 fd=8 if=5 tss=2
2016-11-11T17:45:06Z ntp_core.c:1888:(update_tx_timestamp) Updated TX timestamp delay=0.000018070
2016-11-11T17:45:06Z ntp_io.c:657:(process_message) Received 48 bytes from 192.168.230.245:123 to 192.168.230.94 fd=8 if=5 tss=2 delay=0.000150646
2016-11-11T17:45:06Z ntp_core.c:1484:(receive_packet) NTP packet lvm=44 stratum=1 poll=4 prec=-25 root_delay=0.000000 root_disp=0.000000 refid=47505300 []
2016-11-11T17:45:06Z ntp_core.c:1489:(receive_packet) reference=1478886306.000000000 origin=145355575.308612091 receive=1478886306.852251961 transmit=1478886306.852256715
2016-11-11T17:45:06Z ntp_core.c:1491:(receive_packet) offset=0.000002262 delay=0.000012765 dispersion=0.000000 root_delay=0.000013 root_dispersion=0.000000
2016-11-11T17:45:06Z ntp_core.c:1495:(receive_packet) test123=111 test567=111 testABCD=1111 kod_rate=0 interleaved=0 valid=1 good=1 updated=1
2016-11-11T17:45:06Z sources.c:360:(SRC_AccumulateSample) ip=[192.168.230.245] t=1478886306.852252076 ofs=-0.000002 del=0.000013 disp=0.000000 str=1
2016-11-11T17:45:06Z sourcestats.c:550:(SST_DoNewRegression) off=-2.159884e-06 freq=-7.595780e-10 skew=2.342664e-08 n=19 bs=1 runs=11 asym=0.000000 arun=3
2016-11-11T17:45:06Z sourcestats.c:657:(SST_GetSelectionData) n=20 off=0.000000 dist=0.000013 var=0.000000 first_ago=19.401212 last_ago=0.230354 selok=1
2016-11-11T17:45:06Z sources.c:770:(SRC_SelectSource) badstat=0 sel=1 badstat_reach=0 sel_reach=ff max_reach_ago=19.401212
2016-11-11T17:45:06Z sources.c:1006:(SRC_SelectSource) select score=1.000000 refid=c0a8e6f0 match_refid=c0a8e6f5 status=0 dist=0.000113
2016-11-11T17:45:07Z ntp_core.c:1032:(transmit_timeout) Transmit timeout for [192.168.230.244:123]
2016-11-11T17:45:07Z ntp_io.c:819:(NIO_SendPacket) Sent 48 bytes to 192.168.230.244:123 from [UNSPEC] fd 8
2016-11-11T17:45:07Z ntp_io_linux.c:444:(NIO_Linux_ProcessMessage) Received 48 bytes from error queue for 192.168.230.244:123 fd=8 if=2 tss=2
2016-11-11T17:45:07Z ntp_core.c:1888:(update_tx_timestamp) Updated TX timestamp delay=0.000016331
2016-11-11T17:45:07Z ntp_io.c:657:(process_message) Received 48 bytes from 192.168.230.244:123 to 192.168.230.3 fd=8 if=2 tss=2 delay=0.000089367
2016-11-11T17:45:07Z sourcestats.c:808:(SST_IsGoodSample) Bad sample: offset=0.000006 delay=0.000026 incr_delay=0.000004 allowed=0.000006
2016-11-11T17:45:07Z ntp_core.c:1484:(receive_packet) NTP packet lvm=44 stratum=1 poll=4 prec=-25 root_delay=0.000000 root_disp=0.000000 refid=47505300 []
2016-11-11T17:45:07Z ntp_core.c:1489:(receive_packet) reference=1478886307.000000000 origin=556473881.796577699 receive=1478886307.093656998 transmit=1478886307.093661735
2016-11-11T17:45:07Z ntp_core.c:1491:(receive_packet) offset=-0.000000017 delay=0.000025558 dispersion=0.000000 root_delay=0.000026 root_dispersion=0.000000
2016-11-11T17:45:07Z ntp_core.c:1495:(receive_packet) test123=111 test567=111 testABCD=1101 kod_rate=0 interleaved=0 valid=1 good=0 updated=1
2016-11-11T17:45:07Z ntp_io.c:657:(process_message) Received 48 bytes from 192.168.225.10:123 to 192.168.230.3 fd=10 if=2 tss=2 delay=0.000028297
2016-11-11T17:45:07Z clientlog.c:447:(CLG_LogNTPAccess) NTP hits 25 rate -4 trate -128 tokens 0
2016-11-11T17:45:07Z ntp_io.c:819:(NIO_SendPacket) Sent 48 bytes to 192.168.225.10:123 from 192.168.230.3 fd 10
2016-11-11T17:45:07Z ntp_io_linux.c:444:(NIO_Linux_ProcessMessage) Received 48 bytes from error queue for 192.168.225.10:123 fd=10 if=2 tss=2
2016-11-11T17:45:07Z ntp_core.c:1882:(update_tx_timestamp) Unacceptable TX delay -607092188.785765529
2016-11-11T17:45:07Z ntp_core.c:1032:(transmit_timeout) Transmit timeout for [192.168.230.240:123]
2016-11-11T17:45:07Z ntp_io.c:819:(NIO_SendPacket) Sent 48 bytes to 192.168.230.240:123 from [UNSPEC] fd 8
2016-11-11T17:45:07Z hwclock.c:180:(HCL_AccumulateSample) HW clock samples=16 offset=-3.184661e-08 freq=1.000027282e+00 raw_freq=9.999993816e-01 err=1.032000e-06 ref_diff=2.780387e+01
2016-11-11T17:45:07Z ntp_io_linux.c:444:(NIO_Linux_ProcessMessage) Received 48 bytes from error queue for 192.168.230.240:123 fd=8 if=2 tss=2
2016-11-11T17:45:07Z ntp_core.c:1888:(update_tx_timestamp) Updated TX timestamp delay=0.000016063
2016-11-11T17:45:07Z ntp_io.c:657:(process_message) Received 48 bytes from 192.168.230.240:123 to 192.168.230.3 fd=8 if=2 tss=2 delay=0.000133661
2016-11-11T17:45:07Z ntp_core.c:1484:(receive_packet) NTP packet lvm=44 stratum=1 poll=4 prec=-25 root_delay=0.000000 root_disp=0.000000 refid=47505300 []
2016-11-11T17:45:07Z ntp_core.c:1489:(receive_packet) reference=1478886307.000000000 origin=2382015868.595529085 receive=1478886307.626421859 transmit=1478886307.626426611
2016-11-11T17:45:07Z ntp_core.c:1491:(receive_packet) offset=0.000000052 delay=0.000025615 dispersion=0.000000 root_delay=0.000026 root_dispersion=0.000000
2016-11-11T17:45:07Z ntp_core.c:1495:(receive_packet) test123=111 test567=111 testABCD=1111 kod_rate=0 interleaved=0 valid=1 good=1 updated=1
2016-11-11T17:45:07Z sources.c:360:(SRC_AccumulateSample) ip=[192.168.230.240] t=1478886307.626424183 ofs=-0.000000 del=0.000026 disp=0.000000 str=1
2016-11-11T17:45:07Z sourcestats.c:550:(SST_DoNewRegression) off=-1.539485e-08 freq=-1.146503e-09 skew=1.769057e-08 n=21 bs=0 runs=11 asym=0.000000 arun=0
2016-11-11T17:45:07Z sourcestats.c:657:(SST_GetSelectionData) n=21 off=-0.000000 dist=0.000013 var=0.000000 first_ago=20.175374 last_ago=0.000149 selok=1
2016-11-11T17:45:07Z sources.c:770:(SRC_SelectSource) badstat=0 sel=1 badstat_reach=0 sel_reach=ff max_reach_ago=20.175374
2016-11-11T17:45:07Z sources.c:1006:(SRC_SelectSource) select score=1.000000 refid=c0a8e6f0 match_refid=c0a8e6f0 status=0 dist=0.000113
2016-11-11T17:45:07Z sourcestats.c:687:(SST_GetTrackingData) n=21 freq=-0.000000 (-0.001ppm) skew=0.000000 (0.018ppm) avoff=-0.000000 offsd=0.000000 disp=0.000000
2016-11-11T17:45:07Z local.c:615:(LCL_AccumulateFrequencyAndOffset) old_freq=27.899ppm new_freq=27.899ppm offset=-0.000000sec
2016-11-11T17:45:07Z sys_generic.c:251:(update_slew) slew offset=1.389990e-08 corr_rate=6.585593e-08 base_freq=27.898517 total_freq=27.901443 slew_freq=2.926903e-09 duration=4.749014 slew_error=3.443722e-11
2016-11-11T17:45:07Z sys_generic.c:251:(update_slew) slew offset=-1.495668e-09 corr_rate=6.035216e-08 base_freq=27.898517 total_freq=27.898468 slew_freq=-4.864435e-11 duration=30.746994 slew_error=3.273102e-11
2016-11-11T17:45:07Z rtc_linux.c:295:(slew_samples) dfreq=-0.00000000 doffset=-0.000000 old_fast=-0.993865 old_rate=-95.966 new_fast=-0.993865 new_rate=-95.966
2016-11-11T17:45:07Z sourcestats.c:725:(SST_SlewSamples) n=21 m=16 old_off_time=1478886307.626424183 new=1478886307.626424198 old_off=-0.000000 new_off=0.000000 old_freq=-0.001 new_freq=-0.000
2016-11-11T17:45:07Z sourcestats.c:725:(SST_SlewSamples) n=16 m=16 old_off_time=1478886304.058275227 new=1478886304.058275239 old_off=-0.000006 new_off=-0.000006 old_freq=-0.001 new_freq=-0.000
2016-11-11T17:45:07Z sourcestats.c:725:(SST_SlewSamples) n=18 m=18 old_off_time=1478886306.852252076 new=1478886306.852252090 old_off=-0.000002 new_off=-0.000002 old_freq=-0.001 new_freq=0.000
2016-11-11T17:45:07Z ntp_core.c:1032:(transmit_timeout) Transmit timeout for [192.168.230.245:123]
2016-11-11T17:45:07Z ntp_io.c:819:(NIO_SendPacket) Sent 48 bytes to 192.168.230.245:123 from [UNSPEC] fd 8
2016-11-11T17:45:07Z hwclock.c:180:(HCL_AccumulateSample) HW clock samples=16 offset=1.572480e-08 freq=1.000027275e+00 raw_freq=9.999993761e-01 err=1.002000e-06 ref_diff=1.608795e+00
2016-11-11T17:45:07Z ntp_io.c:657:(process_message) Received 48 bytes from 192.168.230.245:123 to 192.168.230.94 fd=8 if=5 tss=2 delay=0.000027787
2016-11-11T17:45:07Z sourcestats.c:808:(SST_IsGoodSample) Bad sample: offset=-0.000009 delay=0.000031 incr_delay=0.000002 allowed=0.000009
2016-11-11T17:45:07Z ntp_core.c:1484:(receive_packet) NTP packet lvm=44 stratum=1 poll=4 prec=-25 root_delay=0.000000 root_disp=0.000000 refid=47505300 []
2016-11-11T17:45:07Z ntp_core.c:1489:(receive_packet) reference=1478886307.000000000 origin=2165971021.488017498 receive=1478886307.858525343 transmit=1478886307.858530007
2016-11-11T17:45:07Z ntp_core.c:1491:(receive_packet) offset=0.000011027 delay=0.000031051 dispersion=0.000000 root_delay=0.000031 root_dispersion=0.000000
2016-11-11T17:45:07Z ntp_core.c:1495:(receive_packet) test123=111 test567=111 testABCD=1101 kod_rate=0 interleaved=0 valid=1 good=0 updated=1
2016-11-11T17:45:07Z ntp_io_linux.c:444:(NIO_Linux_ProcessMessage) Received 48 bytes from error queue for 192.168.230.245:123 fd=8 if=5 tss=2
2016-11-11T17:45:07Z ntp_core.c:1888:(update_tx_timestamp) Updated TX timestamp delay=0.000018195
2016-11-11T17:45:08Z ntp_core.c:1032:(transmit_timeout) Transmit timeout for [192.168.230.244:123]
2016-11-11T17:45:08Z ntp_io.c:819:(NIO_SendPacket) Sent 48 bytes to 192.168.230.244:123 from [UNSPEC] fd 8
2016-11-11T17:45:08Z ntp_io_linux.c:444:(NIO_Linux_ProcessMessage) Received 48 bytes from error queue for 192.168.230.244:123 fd=8 if=2 tss=2
2016-11-11T17:45:08Z ntp_core.c:1888:(update_tx_timestamp) Updated TX timestamp delay=0.000015625
2016-11-11T17:45:08Z ntp_io.c:657:(process_message) Received 48 bytes from 192.168.230.244:123 to 192.168.230.3 fd=8 if=2 tss=2 delay=0.000090672
2016-11-11T17:45:08Z sourcestats.c:808:(SST_IsGoodSample) Bad sample: offset=0.000006 delay=0.000026 incr_delay=0.000005 allowed=0.000006
2016-11-11T17:45:08Z ntp_core.c:1484:(receive_packet) NTP packet lvm=44 stratum=1 poll=4 prec=-25 root_delay=0.000000 root_disp=0.000000 refid=47505300 []
2016-11-11T17:45:08Z ntp_core.c:1489:(receive_packet) reference=1478886308.000000000 origin=2999853468.944549106 receive=1478886308.097679032 transmit=1478886308.097683772
2016-11-11T17:45:08Z ntp_core.c:1491:(receive_packet) offset=0.000000140 delay=0.000025723 dispersion=0.000000 root_delay=0.000026 root_dispersion=0.000000
2016-11-11T17:45:08Z ntp_core.c:1495:(receive_packet) test123=111 test567=111 testABCD=1101 kod_rate=0 interleaved=0 valid=1 good=0 updated=1
2016-11-11T17:45:08Z ntp_io.c:657:(process_message) Received 48 bytes from 192.168.230.2:123 to 192.168.230.3 fd=10 if=2 tss=2 delay=0.000029560
2016-11-11T17:45:08Z clientlog.c:447:(CLG_LogNTPAccess) NTP hits 25 rate -4 trate -128 tokens 0
2016-11-11T17:45:08Z ntp_io.c:819:(NIO_SendPacket) Sent 48 bytes to 192.168.230.2:123 from 192.168.230.3 fd 10
2016-11-11T17:45:08Z ntp_io_linux.c:444:(NIO_Linux_ProcessMessage) Received 48 bytes from error queue for 192.168.230.2:123 fd=10 if=2 tss=2
2016-11-11T17:45:08Z ntp_core.c:1882:(update_tx_timestamp) Unacceptable TX delay -607092187.579836965
2016-11-11T17:45:08Z ntp_core.c:1032:(transmit_timeout) Transmit timeout for [192.168.230.240:123]
2016-11-11T17:45:08Z ntp_io.c:819:(NIO_SendPacket) Sent 48 bytes to 192.168.230.240:123 from [UNSPEC] fd 8
2016-11-11T17:45:08Z hwclock.c:180:(HCL_AccumulateSample) HW clock samples=16 offset=5.624240e-08 freq=1.000027280e+00 raw_freq=9.999993807e-01 err=1.032000e-06 ref_diff=2.780390e+01
2016-11-11T17:45:08Z ntp_io_linux.c:444:(NIO_Linux_ProcessMessage) Received 48 bytes from error queue for 192.168.230.240:123 fd=8 if=2 tss=2
2016-11-11T17:45:08Z ntp_core.c:1888:(update_tx_timestamp) Updated TX timestamp delay=0.000016179
2016-11-11T17:45:08Z ntp_io.c:657:(process_message) Received 48 bytes from 192.168.230.240:123 to 192.168.230.3 fd=8 if=2 tss=2 delay=0.000138104
2016-11-11T17:45:08Z ntp_core.c:1484:(receive_packet) NTP packet lvm=44 stratum=1 poll=4 prec=-25 root_delay=0.000000 root_disp=0.000000 refid=47505300 []
2016-11-11T17:45:08Z ntp_core.c:1489:(receive_packet) reference=1478886308.000000000 origin=1078761667.718175392 receive=1478886308.643866060 transmit=1478886308.643870760
2016-11-11T17:45:08Z ntp_core.c:1491:(receive_packet) offset=-0.000000128 delay=0.000025620 dispersion=0.000000 root_delay=0.000026 root_dispersion=0.000000
2016-11-11T17:45:08Z ntp_core.c:1495:(receive_packet) test123=111 test567=111 testABCD=1111 kod_rate=0 interleaved=0 valid=1 good=1 updated=1
2016-11-11T17:45:08Z sources.c:360:(SRC_AccumulateSample) ip=[192.168.230.240] t=1478886308.643868538 ofs=0.000000 del=0.000026 disp=0.000000 str=1
2016-11-11T17:45:08Z sourcestats.c:550:(SST_DoNewRegression) off=3.142302e-08 freq=2.666694e-09 skew=1.630232e-08 n=22 bs=0 runs=12 asym=0.000000 arun=0
2016-11-11T17:45:08Z sourcestats.c:657:(SST_GetSelectionData) n=22 off=0.000000 dist=0.000013 var=0.000000 first_ago=21.192822 last_ago=0.000153 selok=1
2016-11-11T17:45:08Z sources.c:770:(SRC_SelectSource) badstat=0 sel=1 badstat_reach=0 sel_reach=ff max_reach_ago=21.192822
2016-11-11T17:45:08Z sources.c:1006:(SRC_SelectSource) select score=1.000000 refid=c0a8e6f0 match_refid=c0a8e6f0 status=0 dist=0.000113
2016-11-11T17:45:08Z sourcestats.c:687:(SST_GetTrackingData) n=22 freq=0.000000 (0.003ppm) skew=0.000000 (0.016ppm) avoff=0.000000 offsd=0.000000 disp=0.000000
2016-11-11T17:45:08Z local.c:615:(LCL_AccumulateFrequencyAndOffset) old_freq=27.899ppm new_freq=27.901ppm offset=0.000000sec
2016-11-11T17:45:08Z sys_generic.c:251:(update_slew) slew offset=-1.446177e-09 corr_rate=6.035216e-08 base_freq=27.900653 total_freq=27.900604 slew_freq=-4.890188e-11 duration=29.573046 slew_error=2.832800e-15
2016-11-11T17:45:08Z sys_generic.c:251:(update_slew) slew offset=2.997818e-08 corr_rate=5.981866e-08 base_freq=27.900653 total_freq=27.915665 slew_freq=1.501194e-08 duration=1.996955 slew_error=1.656693e-10
2016-11-11T17:45:08Z rtc_linux.c:295:(slew_samples) dfreq=0.00000000 doffset=0.000000 old_fast=-0.993865 old_rate=-95.966 new_fast=-0.993865 new_rate=-95.966
2016-11-11T17:45:08Z sourcestats.c:725:(SST_SlewSamples) n=22 m=16 old_off_time=1478886308.643868538 new=1478886308.643868506 old_off=0.000000 new_off=-0.000000 old_freq=0.003 new_freq=0.001
2016-11-11T17:45:08Z sourcestats.c:725:(SST_SlewSamples) n=16 m=16 old_off_time=1478886304.058275239 new=1478886304.058275217 old_off=-0.000006 new_off=-0.000006 old_freq=-0.000 new_freq=-0.002
2016-11-11T17:45:08Z sourcestats.c:725:(SST_SlewSamples) n=18 m=18 old_off_time=1478886306.852252090 new=1478886306.852252062 old_off=-0.000002 new_off=-0.000002 old_freq=0.000 new_freq=-0.002
2016-11-11T17:45:08Z ntp_core.c:1032:(transmit_timeout) Transmit timeout for [192.168.230.245:123]
2016-11-11T17:45:08Z ntp_io.c:819:(NIO_SendPacket) Sent 48 bytes to 192.168.230.245:123 from [UNSPEC] fd 8
2016-11-11T17:45:08Z hwclock.c:180:(HCL_AccumulateSample) HW clock samples=16 offset=7.279701e-10 freq=1.000027278e+00 raw_freq=9.999993765e-01 err=1.032000e-06 ref_diff=1.608822e+00
2016-11-11T17:45:08Z ntp_io_linux.c:444:(NIO_Linux_ProcessMessage) Received 48 bytes from error queue for 192.168.230.245:123 fd=8 if=5 tss=2
2016-11-11T17:45:08Z ntp_core.c:1888:(update_tx_timestamp) Updated TX timestamp delay=0.000018313
2016-11-11T17:45:08Z ntp_io.c:657:(process_message) Received 48 bytes from 192.168.230.245:123 to 192.168.230.94 fd=8 if=5 tss=2 delay=0.000142131
2016-11-11T17:45:08Z ntp_core.c:1484:(receive_packet) NTP packet lvm=44 stratum=1 poll=4 prec=-25 root_delay=0.000000 root_disp=0.000000 refid=47505300 []
2016-11-11T17:45:08Z ntp_core.c:1489:(receive_packet) reference=1478886308.000000000 origin=371775427.845299045 receive=1478886308.868379948 transmit=1478886308.868384653
2016-11-11T17:45:08Z ntp_core.c:1491:(receive_packet) offset=0.000002123 delay=0.000012734 dispersion=0.000000 root_delay=0.000013 root_dispersion=0.000000
2016-11-11T17:45:08Z ntp_core.c:1495:(receive_packet) test123=111 test567=111 testABCD=1111 kod_rate=0 interleaved=0 valid=1 good=1 updated=1
2016-11-11T17:45:08Z sources.c:360:(SRC_AccumulateSample) ip=[192.168.230.245] t=1478886308.868380177 ofs=-0.000002 del=0.000013 disp=0.000000 str=1
2016-11-11T17:45:08Z sourcestats.c:550:(SST_DoNewRegression) off=-2.161245e-06 freq=-4.691400e-10 skew=2.083924e-08 n=19 bs=0 runs=10 asym=0.000000 arun=4
2016-11-11T17:45:08Z sourcestats.c:657:(SST_GetSelectionData) n=22 off=0.000000 dist=0.000013 var=0.000000 first_ago=21.417332 last_ago=0.224663 selok=1
2016-11-11T17:45:08Z sources.c:770:(SRC_SelectSource) badstat=0 sel=1 badstat_reach=0 sel_reach=ff max_reach_ago=21.417332
2016-11-11T17:45:08Z sources.c:1006:(SRC_SelectSource) select score=1.000000 refid=c0a8e6f0 match_refid=c0a8e6f5 status=0 dist=0.000113
2016-11-11T17:45:09Z ntp_core.c:1032:(transmit_timeout) Transmit timeout for [192.168.230.244:123]
2016-11-11T17:45:09Z ntp_io.c:819:(NIO_SendPacket) Sent 48 bytes to 192.168.230.244:123 from [UNSPEC] fd 8
2016-11-11T17:45:09Z ntp_io_linux.c:444:(NIO_Linux_ProcessMessage) Received 48 bytes from error queue for 192.168.230.244:123 fd=8 if=2 tss=2
2016-11-11T17:45:09Z ntp_core.c:1888:(update_tx_timestamp) Updated TX timestamp delay=0.000016422
2016-11-11T17:45:09Z ntp_io.c:657:(process_message) Received 48 bytes from 192.168.230.244:123 to 192.168.230.3 fd=8 if=2 tss=2 delay=0.000077992
2016-11-11T17:45:09Z sourcestats.c:808:(SST_IsGoodSample) Bad sample: offset=0.000006 delay=0.000026 incr_delay=0.000006 allowed=0.000006
2016-11-11T17:45:09Z ntp_core.c:1484:(receive_packet) NTP packet lvm=44 stratum=1 poll=4 prec=-25 root_delay=0.000000 root_disp=0.000000 refid=47505300 []
2016-11-11T17:45:09Z ntp_core.c:1489:(receive_packet) reference=1478886309.000000000 origin=2721962017.860266602 receive=1478886309.098264489 transmit=1478886309.098269189
2016-11-11T17:45:09Z ntp_core.c:1491:(receive_packet) offset=-0.000000078 delay=0.000025635 dispersion=0.000000 root_delay=0.000026 root_dispersion=0.000000
2016-11-11T17:45:09Z ntp_core.c:1495:(receive_packet) test123=111 test567=111 testABCD=1101 kod_rate=0 interleaved=0 valid=1 good=0 updated=1
2016-11-11T17:45:09Z ntp_io.c:657:(process_message) Received 48 bytes from 192.168.225.10:123 to 192.168.230.3 fd=10 if=2 tss=2 delay=0.000028947
2016-11-11T17:45:09Z clientlog.c:447:(CLG_LogNTPAccess) NTP hits 26 rate -4 trate -128 tokens 0
2016-11-11T17:45:09Z ntp_io.c:819:(NIO_SendPacket) Sent 48 bytes to 192.168.225.10:123 from 192.168.230.3 fd 10
2016-11-11T17:45:09Z ntp_io_linux.c:444:(NIO_Linux_ProcessMessage) Received 48 bytes from error queue for 192.168.225.10:123 fd=10 if=2 tss=2
2016-11-11T17:45:09Z ntp_core.c:1882:(update_tx_timestamp) Unacceptable TX delay -607092186.765281320
2016-11-11T17:45:09Z ntp_core.c:1032:(transmit_timeout) Transmit timeout for [192.168.230.240:123]
2016-11-11T17:45:09Z ntp_io.c:819:(NIO_SendPacket) Sent 48 bytes to 192.168.230.240:123 from [UNSPEC] fd 8
2016-11-11T17:45:09Z hwclock.c:180:(HCL_AccumulateSample) HW clock samples=16 offset=3.595919e-08 freq=1.000027279e+00 raw_freq=9.999993774e-01 err=1.032000e-06 ref_diff=2.780392e+01
2016-11-11T17:45:09Z ntp_io_linux.c:444:(NIO_Linux_ProcessMessage) Received 48 bytes from error queue for 192.168.230.240:123 fd=8 if=2 tss=2
2016-11-11T17:45:09Z ntp_core.c:1888:(update_tx_timestamp) Updated TX timestamp delay=0.000016144
2016-11-11T17:45:09Z ntp_io.c:657:(process_message) Received 48 bytes from 192.168.230.240:123 to 192.168.230.3 fd=8 if=2 tss=2 delay=0.000132540
2016-11-11T17:45:09Z ntp_core.c:1484:(receive_packet) NTP packet lvm=44 stratum=1 poll=4 prec=-25 root_delay=0.000000 root_disp=0.000000 refid=47505300 []
2016-11-11T17:45:09Z ntp_core.c:1489:(receive_packet) reference=1478886309.000000000 origin=3772786195.368785623 receive=1478886309.650833047 transmit=1478886309.650837760
2016-11-11T17:45:09Z ntp_core.c:1491:(receive_packet) offset=-0.000000060 delay=0.000025638 dispersion=0.000000 root_delay=0.000026 root_dispersion=0.000000
2016-11-11T17:45:09Z ntp_core.c:1495:(receive_packet) test123=111 test567=111 testABCD=1111 kod_rate=0 interleaved=0 valid=1 good=1 updated=1
2016-11-11T17:45:09Z sources.c:360:(SRC_AccumulateSample) ip=[192.168.230.240] t=1478886309.650835463 ofs=0.000000 del=0.000026 disp=0.000000 str=1
2016-11-11T17:45:09Z sourcestats.c:550:(SST_DoNewRegression) off=1.177625e-08 freq=1.512319e-09 skew=1.478585e-08 n=23 bs=0 runs=12 asym=0.000000 arun=0
2016-11-11T17:45:09Z sourcestats.c:657:(SST_GetSelectionData) n=23 off=0.000000 dist=0.000013 var=0.000000 first_ago=22.199784 last_ago=0.000148 selok=1
2016-11-11T17:45:09Z sources.c:770:(SRC_SelectSource) badstat=0 sel=1 badstat_reach=0 sel_reach=ff max_reach_ago=22.199784
2016-11-11T17:45:09Z sources.c:1006:(SRC_SelectSource) select score=1.000000 refid=c0a8e6f0 match_refid=c0a8e6f0 status=0 dist=0.000113
2016-11-11T17:45:09Z sourcestats.c:687:(SST_GetTrackingData) n=23 freq=0.000000 (0.002ppm) skew=0.000000 (0.015ppm) avoff=0.000000 offsd=0.000000 disp=0.000000
2016-11-11T17:45:09Z local.c:615:(LCL_AccumulateFrequencyAndOffset) old_freq=27.901ppm new_freq=27.902ppm offset=0.000000sec
2016-11-11T17:45:09Z sys_generic.c:251:(update_slew) slew offset=1.486176e-08 corr_rate=5.981866e-08 base_freq=27.901885 total_freq=27.905563 slew_freq=3.678709e-09 duration=4.039938 slew_error=1.246656e-10
2016-11-11T17:45:09Z sys_generic.c:251:(update_slew) slew offset=2.663866e-08 corr_rate=5.730043e-08 base_freq=27.901885 total_freq=27.914261 slew_freq=1.237646e-08 duration=2.152365 slew_error=9.567528e-11
2016-11-11T17:45:09Z rtc_linux.c:295:(slew_samples) dfreq=0.00000000 doffset=0.000000 old_fast=-0.993865 old_rate=-95.966 new_fast=-0.993865 new_rate=-95.966
2016-11-11T17:45:09Z sourcestats.c:725:(SST_SlewSamples) n=23 m=16 old_off_time=1478886309.650835463 new=1478886309.650835451 old_off=0.000000 new_off=-0.000000 old_freq=0.002 new_freq=0.000
2016-11-11T17:45:09Z sourcestats.c:725:(SST_SlewSamples) n=16 m=16 old_off_time=1478886304.058275217 new=1478886304.058275212 old_off=-0.000006 new_off=-0.000006 old_freq=-0.002 new_freq=-0.004
2016-11-11T17:45:09Z sourcestats.c:725:(SST_SlewSamples) n=19 m=18 old_off_time=1478886308.868380177 new=1478886308.868380166 old_off=-0.000002 new_off=-0.000002 old_freq=-0.000 new_freq=-0.002
2016-11-11T17:45:09Z ntp_core.c:1032:(transmit_timeout) Transmit timeout for [192.168.230.245:123]
2016-11-11T17:45:09Z ntp_io.c:819:(NIO_SendPacket) Sent 48 bytes to 192.168.230.245:123 from [UNSPEC] fd 8
2016-11-11T17:45:09Z hwclock.c:180:(HCL_AccumulateSample) HW clock samples=16 offset=-1.511677e-09 freq=1.000027279e+00 raw_freq=9.999993764e-01 err=1.032000e-06 ref_diff=1.608850e+00
2016-11-11T17:45:09Z ntp_io.c:657:(process_message) Received 48 bytes from 192.168.230.245:123 to 192.168.230.94 fd=8 if=5 tss=2 delay=0.000027065
2016-11-11T17:45:09Z sourcestats.c:808:(SST_IsGoodSample) Bad sample: offset=-0.000010 delay=0.000032 incr_delay=0.000001 allowed=0.000010
2016-11-11T17:45:09Z ntp_core.c:1484:(receive_packet) NTP packet lvm=44 stratum=1 poll=4 prec=-25 root_delay=0.000000 root_disp=0.000000 refid=47505300 []
2016-11-11T17:45:09Z ntp_core.c:1489:(receive_packet) reference=1478886309.000000000 origin=1193667289.735591267 receive=1478886309.882253566 transmit=1478886309.882258238
2016-11-11T17:45:09Z ntp_core.c:1491:(receive_packet) offset=0.000011883 delay=0.000032293 dispersion=0.000000 root_delay=0.000032 root_dispersion=0.000000
2016-11-11T17:45:09Z ntp_core.c:1495:(receive_packet) test123=111 test567=111 testABCD=1101 kod_rate=0 interleaved=0 valid=1 good=0 updated=1
2016-11-11T17:45:09Z ntp_io_linux.c:444:(NIO_Linux_ProcessMessage) Received 48 bytes from error queue for 192.168.230.245:123 fd=8 if=5 tss=2
2016-11-11T17:45:09Z ntp_core.c:1888:(update_tx_timestamp) Updated TX timestamp delay=0.000019606


--
To unsubscribe email chrony-dev-request@xxxxxxxxxxxxxxxxxxxx with "unsubscribe" in the subject.
For help email chrony-dev-request@xxxxxxxxxxxxxxxxxxxx with "help" in the subject.
Trouble?  Email listmaster@xxxxxxxxxxxxxxxxxxxx.