[Linuxptp-users] clockcheck - need to filter large spurious phase jumps?

[Rich S. <sch...@gm...>]

This is Rich Schmidt, linuxptp newbie.

I am testing linuxptp on this system at the US Naval Observatory:
Supermicro SYS-5015A-EHF-D525 (Atom)
Intel 82547L  NICs   driver: e1000e version: 2.5.4-NAPI firmware-version:
1.9-0
Debian with kernel 3.12.0-rc

Running:
Sync PHC to USNO Master Clock via Zyfer Gsync PTP GrandMaster:
ptp4l -i eth1 -l 7 -s -p /dev/ptp1

Sync CLOCK_REALTIME to PHC:
phc2sys -s /dev/ptp1 -L 100000000 -l 7 -R 0.25 -O 0

Things seem to work fine for a while, then I get a single large phase
offset detected by ptp4l.  The  -L freq limit was an attempt to control
these offsets, but did not help.

Are these large phase jumps filtered out by ptp4l?  It seems not, because
phc2sys sees them. Or is this some unreliability in the Intel
82547L NICs?  Is the PHC read failing?   Thank you for your thoughts.

Here is a sample.  The clock is not being steered by NTP or any other
program.

Nov  5 18:12:27 pluto ptp4l: [354666.428] master offset         57 s2 freq
+34356 path delay      6086
Nov  5 18:12:29 pluto ptp4l: [354668.428] master offset       -139 s2 freq
+34266 path delay      6092
Nov  5 18:12:30 pluto phc2sys: [354669.993] phc offset      4529 s2 freq
+8805 delay   4715
Nov  5 18:12:31 pluto ptp4l: [354670.428] master offset        -32 s2 freq
+34299 path delay      6092
Nov  5 18:12:33 pluto ptp4l: [354672.428] master offset         20 s2 freq
+34320 path delay      6092
Nov  5 18:12:34 pluto phc2sys: [354673.993] phc offset       470 s2 freq
+7931 delay   4705
Nov  5 18:12:35 pluto ptp4l: [354674.428] master offset         54 s2 freq
+34340 path delay      6095
Nov  5 18:12:37 pluto ptp4l: [354676.428] master offset        -15 s2 freq
+34314 path delay      6095
Nov  5 18:12:38 pluto phc2sys: [354677.993] phc offset     -6992 s2 freq
+3968 delay   4870
Nov  5 18:12:39 pluto ptp4l: [354678.428] master offset        -19 s2 freq
+34309 path delay      6096
Nov  5 18:12:41 pluto ptp4l: [354680.429] master offset         55 s2 freq
+34344 path delay      6096
Nov  5 18:12:42 pluto phc2sys: [354681.994] phc offset     11326 s2 freq
+11945 delay   4715
Nov  5 18:12:43 pluto ptp4l: [354682.428] master offset        -90 s2 freq
+34279 path delay      6096
Nov  5 18:12:45 pluto ptp4l: [354684.429] master offset        -49 s2 freq
+34286 path delay      6096
Nov  5 18:12:46 pluto phc2sys: [354685.994] phc offset -70368744182111 s2
freq -500000 delay   4715

*Nov  5 18:12:47 pluto ptp4l: [354686.428] clockcheck: clock jumped forward
or running faster than expected!Nov  5 18:12:47 pluto ptp4l: [354686.429]
master offset 70368744177715 s0 freq      +0 path delay      6095*
Nov  5 18:12:47 pluto ptp4l: [354686.430] port 1: SLAVE to UNCALIBRATED on
SYNCHRONIZATION_FAULT
Nov  5 18:12:49 pluto ptp4l: [354688.430] master offset 70368744177775 s1
freq  +34334 path delay      6095
Nov  5 18:12:50 pluto phc2sys: [354689.994] phc offset   2028439 s2 freq
+500000 delay   4717
Nov  5 18:12:51 pluto ptp4l: [354690.431] master offset      -4473 s2 freq
+32097 path delay      6095
Nov  5 18:12:51 pluto ptp4l: [354690.431] port 1: UNCALIBRATED to SLAVE on
MASTER_CLOCK_SELECTED
Nov  5 18:12:53 pluto ptp4l: [354692.430] master offset        -48 s2 freq
+33639 path delay      6095
Nov  5 18:12:54 pluto phc2sys: [354693.994] phc offset     52807 s2 freq
+38158 delay   4712
Nov  5 18:12:55 pluto ptp4l: [354694.429] master offset       1367 s2 freq
+34339 path delay      6095
Nov  5 18:12:57 pluto ptp4l: [354696.429] master offset       1179 s2 freq
+34450 path delay      6095
Nov  5 18:12:58 pluto phc2sys: [354697.995] phc offset    -65029 s2 freq
-10810 delay   4715
Nov  5 18:12:59 pluto ptp4l: [354698.429] master offset        866 s2 freq
+34470 path delay      6104
...
Nov  5 18:19:02 pluto phc2sys: [355062.012] phc offset      6991 s2 freq
+10913 delay   4704
Nov  5 18:19:03 pluto ptp4l: [355062.442] master offset         -8 s2 freq
+34342 path delay      6109
Nov  5 18:19:05 pluto ptp4l: [355064.442] master offset       -160 s2 freq
+34265 path delay      6109
Nov  5 18:19:06 pluto phc2sys: [355066.012] phc offset    -11133 s2 freq
+3042 delay   4715
Nov  5 18:19:07 pluto ptp4l: [355066.442] master offset        -73 s2 freq
+34285 path delay      6102
Nov  5 18:19:09 pluto ptp4l: [355068.442] master offset          8 s2 freq
+34314 path delay      6102
Nov  5 18:19:10 pluto phc2sys: [355070.012] phc offset      6061 s2 freq
+9159 delay   4715
Nov  5 18:19:11 pluto ptp4l: [355070.442] master offset         89 s2 freq
+34356 path delay      6098
Nov  5 18:19:13 pluto ptp4l: [355072.442] master offset         20 s2 freq
+34335 path delay      6098

*Nov  5 18:19:14 pluto phc2sys: [355074.012] phc offset -70368744173547 s2
freq -500000 delay   4715Nov  5 18:19:15 pluto ptp4l: [355074.441]
clockcheck: clock jumped forward or running faster than expected!*
Nov  5 18:19:15 pluto ptp4l: [355074.442] master offset 70368744177526 s0
freq      +0 path delay      6103
Nov  5 18:19:15 pluto ptp4l: [355074.443] port 1: SLAVE to UNCALIBRATED on
SYNCHRONIZATION_FAULT
Nov  5 18:19:17 pluto ptp4l: [355076.444] master offset 70368744177457 s1
freq  +34293 path delay      6103
Nov  5 18:19:18 pluto phc2sys: [355078.013] phc offset   2034086 s2 freq
+500000 delay   4762
Nov  5 18:19:19 pluto ptp4l: [355078.444] master offset      -4252 s2 freq
+32167 path delay      6103
Nov  5 18:19:19 pluto ptp4l: [355078.445] port 1: UNCALIBRATED to SLAVE on
MASTER_CLOCK_SELECTED
Nov  5 18:19:21 pluto ptp4l: [355080.443] master offset         71 s2 freq
+33691 path delay      6103
Nov  5 18:19:22 pluto phc2sys: [355082.013] phc offset     54724 s2 freq
+37742 delay   4713
Nov  5 18:19:23 pluto ptp4l: [355082.443] master offset       1255 s2 freq
+34294 path delay      6103
Nov  5 18:19:25 pluto ptp4l: [355084.443] master offset       1298 s2 freq
+34503 path delay      6102
Nov  5 18:19:26 pluto phc2sys: [355086.013] phc offset    -71161 s2 freq
-15078 delay   4706
Nov  5 18:19:27 pluto ptp4l: [355086.443] master offset       1034 s2 freq
+34566 path delay      6102
Nov  5 18:19:29 pluto ptp4l: [355088.443] master offset        540 s2 freq
+34474 path delay      6106
...
Nov  5 19:16:34 pluto phc2sys: [358514.174] phc offset      -691 s2 freq
+10120 delay   4714
Nov  5 19:16:35 pluto ptp4l: [358514.563] master offset         56 s2 freq
+34310 path delay      6097
Nov  5 19:16:37 pluto ptp4l: [358516.563] master offset         98 s2 freq
+34340 path delay      6097
Nov  5 19:16:38 pluto phc2sys: [358518.174] phc offset    -37068 s2 freq
-10095 delay   4716

*Nov  5 19:16:39 pluto ptp4l: [358518.562] clockcheck: clock jumped forward
or running faster than expected!Nov  5 19:16:39 pluto ptp4l: [358518.563]
master offset 70368744177566 s0 freq      +0 path delay      6100*
Nov  5 19:16:39 pluto ptp4l: [358518.563] port 1: SLAVE to UNCALIBRATED on
SYNCHRONIZATION_FAULT
Nov  5 19:16:41 pluto ptp4l: [358520.563] master offset 70368744177456 s1
freq  +34250 path delay      6100
Nov  5 19:16:42 pluto phc2sys: [358522.175] phc offset     59749 s2 freq
+32034 delay   4714
Nov  5 19:16:43 pluto ptp4l: [358522.563] master offset      -4442 s2 freq
+32029 path delay      6100
Nov  5 19:16:43 pluto ptp4l: [358522.563] port 1: UNCALIBRATED to SLAVE on
MASTER_CLOCK_SELECTED
Nov  5 19:16:45 pluto ptp4l: [358524.564] master offset        126 s2 freq
+33647 path delay      6100
Nov  5 19:16:46 pluto phc2sys: [358526.175] phc offset    -32436 s2 freq
-743 delay   4933
Nov  5 19:16:47 pluto ptp4l: [358526.563] master offset       1510 s2 freq
+34358 path delay      6100
Nov  5 19:16:49 pluto ptp4l: [358528.563] master offset       1356 s2 freq
+34508 path delay      6106
Nov  5 19:16:50 pluto phc2sys: [358530.175] phc offset    -19275 s2 freq
-3235 delay   4716
...