[Linuxptp-users] linuxptp servo can't converge

[Hamilton A. <ale...@gm...>]

Hi, my friends:
I got an issue for linux ptp servo.  my board is using zarlink DPLL,
currently I have connected my board with an external test equipment calnex,
calnex as master and my board as slave.
first I let my board run with freerun mode, and from ptp4l output, get the
phase offset and frequency ppb, then I manually adjust the phase and
frequency using my cmds, it works well, can converge to +/- 10ns, that
means functionally it is working well.  then I make "freerun to 0" and try
to use linuxptp servo to adjust the frequency, it can't coverge.

first run linreg servo, you can see the master offset and frequency ppb
become larger and larger.


physical port 2, system_port_id 4

ptp4l[45906.268]: port 1: INITIALIZING to SLAVE on INIT_COMPLETE

physical port 4, system_port_id 5

ptp4l[45906.292]: port 2: INITIALIZING to MASTER on INIT_COMPLETE

ptp4l[45906.292]: uds: bind failed: No such file or directory

ptp4l[45906.292]: port 0: INITIALIZING to FAULTY on FAULT_DETECTED
(FT_UNSPECIFIED)

ptp4l[45906.379]: master offset -726071083407 s0 freq      -0 path delay
  9058

ptp4l[45906.379]: port 1: new foreign master 000000.0000.000001-1

ptp4l[45906.441]: master offset -726071083408 s0 freq      -0 path delay
  9057

ptp4l[45906.504]: master offset -726071083410 s0 freq      -0 path delay
  9057

DPLL1, freq ppb is 23.9966, reg_val is 0xffffffffff98ef84

Set time 726 secs with direction forward

DPLL1, adjust phase offset for HP Output 0

offset step is 71083411 NS, reg_val is 0x1b1dbd

DPLL1, adjust phase offset for HP Output 4

offset step is 71083411 NS, reg_val is 0x1b1dbd

iofpga step time

ptp4l[45917.174]: master offset -726071083411 s1 freq     -24 path delay
  9058

physical port 2, system_port_id 4

physical port 2, system_port_id 4

ptp4l[45917.317]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[45917.379]: selected best master clock 000000.0000.000001

ptp4l[45917.379]: updating UTC offset to 37

ptp4l[45917.379]: port 2: master state recommended in slave only mode

ptp4l[45917.379]: port 2: defaultDS.priority1 probably misconfigured

ptp4l[45919.067]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[45924.005]: master offset         -4 s0 freq     -24 path delay
  9058

ptp4l[45924.067]: master offset         -3 s0 freq     -24 path delay
  9058

ptp4l[45924.129]: master offset         -5 s0 freq     -24 path delay
  9059

DPLL1, freq ppb is 96.0000, reg_val is 0xfffffffffe63aedd

ptp4l[45926.294]: master offset         -4 s2 freq     -96 path delay
  9058

DPLL1, freq ppb is 64.0000, reg_val is 0xfffffffffeed1f37

ptp4l[45928.397]: master offset         -2 s2 freq     -64 path delay
  9058

physical port 2, system_port_id 4

physical port 2, system_port_id 4

ptp4l[45928.465]: clockcheck: clock jumped backward or running slower than
expected!

ptp4l[45928.505]: master offset        246 s0 freq     -64 path delay
  9058

ptp4l[45928.567]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[45928.567]: master offset        255 s0 freq     -64 path delay
  9058

ptp4l[45928.629]: master offset        268 s0 freq     -64 path delay
  9058

ptp4l[45928.692]: master offset        277 s0 freq     -64 path delay
  9058

DPLL1, freq ppb is -4720.0001, reg_val is 0x4f303eff

ptp4l[45930.856]: master offset        288 s2 freq   +4720 path delay
  9057

ptp4l[45930.856]: clockcheck: clock jumped backward or running slower than
expected!

ptp4l[45930.856]: master offset        298 s0 freq   +4720 path delay
  9057

physical port 2, system_port_id 4

ptp4l[45930.886]: master offset         60 s0 freq   +4720 path delay
  9057

ptp4l[45930.942]: master offset       -225 s0 freq   +4720 path delay
  9057

ptp4l[45931.003]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[45931.003]: master offset       -511 s0 freq   +4720 path delay
  9058

ptp4l[45931.067]: master offset       -794 s0 freq   +4720 path delay
  9058

ptp4l[45931.129]: master offset      -1081 s0 freq   +4720 path delay
  9059

DPLL1, freq ppb is 21696.0001, reg_val is 0xfffffffe94006393

ptp4l[45933.293]: master offset      -1366 s2 freq  -21696 path delay
  9059

ptp4l[45933.293]: clockcheck: clock jumped backward or running slower than
expected!

ptp4l[45933.293]: master offset      -1651 s0 freq  -21696 path delay
  9059

physical port 2, system_port_id 4

physical port 2, system_port_id 4

ptp4l[45933.380]: master offset      -7331 s0 freq  -21696 path delay
  9059

ptp4l[45933.442]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[45933.442]: master offset      -6257 s0 freq  -21696 path delay
  9058

ptp4l[45933.504]: master offset      -5187 s0 freq  -21696 path delay
  9058

ptp4l[45933.566]: master offset      -4116 s0 freq  -21696 path delay
  9058

DPLL1, freq ppb is 53256.0250, reg_val is 0xfffffffc82831d44

ptp4l[45935.731]: master offset      -3043 s2 freq  -53256 path delay
  9057

ptp4l[45935.731]: clockcheck: clock jumped backward or running slower than
expected!

ptp4l[45935.731]: master offset      -1973 s0 freq  -53256 path delay
  9057

physical port 2, system_port_id 4

ptp4l[45935.761]: master offset      39851 s0 freq  -53256 path delay
  9057

ptp4l[45935.817]: master offset      44249 s0 freq  -53256 path delay
  9057

ptp4l[45935.879]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[45935.879]: master offset      48706 s0 freq  -53256 path delay
  9001

ptp4l[45935.942]: master offset      53285 s0 freq  -53256 path delay
  8821

ptp4l[45936.004]: master offset      57955 s0 freq  -53256 path delay
  8552

DPLL1, freq ppb is -1019743.6404, reg_val is 0x42d4759606

ptp4l[45938.168]: master offset      62438 s2 freq +1019744 path delay
  8468

ptp4l[45938.168]: clockcheck: clock jumped backward or running slower than
expected!

ptp4l[45938.168]: master offset      66837 s0 freq +1019744 path delay
  8468

physical port 2, system_port_id 4

physical port 2, system_port_id 4

ptp4l[45938.254]: master offset      28720 s0 freq +1019744 path delay
  8468

ptp4l[45938.317]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[45938.317]: master offset     -30614 s0 freq +1019744 path delay
  8468

ptp4l[45938.379]: master offset     -89948 s0 freq +1019744 path delay
  8468

ptp4l[45938.441]: master offset    -149283 s0 freq +1019744 path delay
  8468

DPLL1, freq ppb is 3267428.8999, reg_val is 0xffffff29dda3bba4

ptp4l[45940.606]: master offset    -208618 s2 freq -3267429 path delay
  8468

ptp4l[45940.606]: clockcheck: clock jumped backward or running slower than
expected!

ptp4l[45940.606]: master offset    -267951 s0 freq -3267429 path delay
  8468

physical port 2, system_port_id 4

ptp4l[45940.692]: master offset   -1689017 s0 freq -3267429 path delay
  9872

ptp4l[45940.754]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[45940.754]: master offset   -1544139 s0 freq -3267429 path delay
  9872

ptp4l[45940.816]: master offset   -1399259 s0 freq -3267429 path delay
  9872

ptp4l[45940.879]: master offset   -1254378 s0 freq -3267429 path delay
  9872

DPLL1, freq ppb is 18699111.5531, reg_val is 0xfffffb3688f76a3e

ptp4l[45943.043]: master offset   -1109498 s2 freq -18699112 path delay
  9872

ptp4l[45943.043]: clockcheck: clock jumped backward or running slower than
expected!

ptp4l[45943.043]: master offset    -964619 s0 freq -18699112 path delay
  9872

physical port 2, system_port_id 4

physical port 2, system_port_id 4

ptp4l[45943.129]: master offset    7300881 s0 freq -18699112 path delay
  9872

ptp4l[45943.192]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[45943.192]: master offset    8615075 s0 freq -18699112 path delay
  9254

ptp4l[45943.254]: master offset    9928646 s0 freq -18699112 path delay
  9254

ptp4l[45943.316]: master offset   11262189 s0 freq -18699112 path delay
  -10712

DPLL1, freq ppb is -204240074.5938, reg_val is 0x344913d8e9b0

ptp4l[45945.481]: master offset   12613167 s2 freq +204240075 path delay
  -48115



# stop the ptp, and run PI servo

then i use PI servo, use default configs and just change some items.

freq_est_interval       0

logSyncInterval         -4
logMinDelayReqInterval  -4


I have add dump for the details of PI servo sample. we can see that the
master offset and frequency ppb becomes larger and larger, there is no
trend to converge.

any ideas?  I am very appreciated for your help!


Thanks

Alex



ptp4l[46025.068]: create transport success

ptp4l[46025.068]: ports create done


physical port 2, system_port_id 4

ptp4l[46025.082]: port 1: INITIALIZING to SLAVE on INIT_COMPLETE

physical port 4, system_port_id 5

ptp4l[46025.098]: port 2: INITIALIZING to MASTER on INIT_COMPLETE

ptp4l[46025.098]: uds: bind failed: No such file or directory

ptp4l[46025.098]: port 0: INITIALIZING to FAULTY on FAULT_DETECTED
(FT_UNSPECIFIED)

ptp4l[46025.128]: port 1: new foreign master 000000.0000.000001-1

ptp4l[46025.192]: offset is 326995475, local_ts is 1662006655520457321,
weight is 1.000000

ptp4l[46025.192]: s->count-->1

ptp4l[46025.192]: s->last_freq 0.000000

ptp4l[46025.192]: master offset  326995475 s0 freq      +0 path delay
  -46847

ptp4l[46025.254]: offset is 327207298, local_ts is 1662006655583168984,
weight is 1.000000

ptp4l[46025.254]: case 1

ptp4l[46025.254]: localdiff is 0.062774, s->ki is 0.098963

ptp4l[46025.254]: freq_est_interval is 0.161676

ptp4l[46025.254]: s->last_freq 0.000000

ptp4l[46025.254]: master offset  327207298 s0 freq      +0 path delay
  -46847

ptp4l[46025.316]: offset is 327421028, local_ts is 1662006655645882759,
weight is 1.000000

ptp4l[46025.316]: case 1

ptp4l[46025.316]: localdiff is 0.125551, s->ki is 0.098963

ptp4l[46025.316]: freq_est_interval is 0.161676

ptp4l[46025.316]: s->last_freq 0.000000

ptp4l[46025.316]: master offset  327421028 s0 freq      +0 path delay
  -46847

ptp4l[46025.378]: offset is 327637831, local_ts is 1662006655708599190,
weight is 1.000000

ptp4l[46025.378]: case 1

ptp4l[46025.378]: localdiff is 0.188330, s->ki is 0.098963

ptp4l[46025.378]: freq_est_interval is 0.161676

ptp4l[46025.378]: s->drift is 3414210.794302

ptp4l[46025.378]: servo->max_frequency is 900000000.000000

ptp4l[46025.378]: s->drift is 3414210.794302

ptp4l[46025.378]: 2: servo->max_frequency is 900000000.000000

ptp4l[46025.378]: case 1 ppb is 3414210.794302

ptp4l[46025.378]: s->last_freq 3414210.794302

DPLL1, freq ppb is -3414210.7943, reg_val is 0xdfc0f3b403

Set time 0 secs with direction backward

DPLL1, adjust phase offset for HP Output 0

offset step is -327637831 NS, reg_val is 0xff83041f

DPLL1, adjust phase offset for HP Output 4

offset step is -327637831 NS, reg_val is 0xff83041f

iofpga step time

ptp4l[46035.335]: master offset  327637831 s1 freq +3414211 path delay
  -47264

physical port 2, system_port_id 4

physical port 2, system_port_id 4

ptp4l[46035.398]: selected best master clock 000000.0000.000001

ptp4l[46035.398]: updating UTC offset to 37

ptp4l[46035.398]: port 2: master state recommended in slave only mode

ptp4l[46035.398]: port 2: defaultDS.priority1 probably misconfigured

ptp4l[46035.441]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46042.191]: offset is 57522521, local_ts is 1662006672250967258,
weight is 1.000000

ptp4l[46042.191]: s->count-->1

ptp4l[46042.191]: s->last_freq 3414210.794302

ptp4l[46042.191]: master offset   57522521 s0 freq +3414211 path delay
  -63854

ptp4l[46042.253]: offset is 57727572, local_ts is 1662006672313682432,
weight is 1.000000

ptp4l[46042.253]: case 1

ptp4l[46042.253]: localdiff is 0.062778, s->ki is 0.098963

ptp4l[46042.253]: freq_est_interval is 0.161676

ptp4l[46042.253]: s->last_freq 3414210.794302

ptp4l[46042.253]: master offset   57727572 s0 freq +3414211 path delay
  -53775

ptp4l[46042.316]: offset is 57934316, local_ts is 1662006672376397922,
weight is 1.000000

ptp4l[46042.316]: case 1

ptp4l[46042.316]: localdiff is 0.125556, s->ki is 0.098963

ptp4l[46042.316]: freq_est_interval is 0.161676

ptp4l[46042.316]: s->last_freq 3414210.794302

ptp4l[46042.316]: master offset   57934316 s0 freq +3414211 path delay
  -45074

ptp4l[46042.378]: offset is 58146375, local_ts is 1662006672439111255,
weight is 1.000000

ptp4l[46042.378]: case 1

ptp4l[46042.378]: localdiff is 0.188332, s->ki is 0.098963

ptp4l[46042.378]: freq_est_interval is 0.161676

ptp4l[46042.378]: s->drift is 6718722.449485

ptp4l[46042.378]: servo->max_frequency is 900000000.000000

ptp4l[46042.378]: s->drift is 6718722.449485

ptp4l[46042.378]: 2: servo->max_frequency is 900000000.000000

ptp4l[46042.378]: case 1 ppb is 6718722.449485

ptp4l[46042.378]: s->last_freq 6718722.449485

DPLL1, freq ppb is -6718722.4495, reg_val is 0x1b851753102

ptp4l[46044.480]: master offset   58146375 s2 freq +6718722 path delay
  -45074

ptp4l[46044.480]: clockcheck: clock jumped backward or running slower than
expected!

ptp4l[46044.480]: offset is 58358368, local_ts is 1662006672501821859,
weight is 1.000000

ptp4l[46044.480]: s->count-->1

ptp4l[46044.480]: s->last_freq 6718722.449485

ptp4l[46044.480]: master offset   58358368 s0 freq +6718722 path delay
  -45074

physical port 2, system_port_id 4

ptp4l[46044.566]: offset is 65631690, local_ts is 1662006674634086570,
weight is 1.000000

ptp4l[46044.566]: case 1

ptp4l[46044.566]: localdiff is 2.134397, s->ki is 0.098963

ptp4l[46044.566]: freq_est_interval is 0.161676

ptp4l[46044.566]: s->drift is 10106882.714449

ptp4l[46044.566]: servo->max_frequency is 900000000.000000

ptp4l[46044.566]: s->drift is 10106882.714449

ptp4l[46044.566]: 2: servo->max_frequency is 900000000.000000

ptp4l[46044.566]: case 1 ppb is 10106882.714449

ptp4l[46044.566]: s->last_freq 10106882.714449

DPLL1, freq ppb is -10106882.7144, reg_val is 0x2965d5ab91b

ptp4l[46046.668]: master offset   65631690 s2 freq +10106883 path delay
  -53775

ptp4l[46046.668]: offset is 65848613, local_ts is 1662006674696803537,
weight is 1.000000

ptp4l[46046.668]: s->ki 0.098963, offset 65848613, weight 1.000000

ptp4l[46046.668]: s->kp 1.608178, offset 65848613, weight 1.000000,
s->drift 10106882.714449, ki_term 6516582.432519

ptp4l[46046.668]: case 2 ppb 122519735.951679

ptp4l[46046.668]: servo->max_frequency is 900000000.000000

ptp4l[46046.668]: 2:case 2 ppb 122519735.951679

ptp4l[46046.668]: 2: servo->max_frequency is 900000000.000000

ptp4l[46046.668]: s->last_freq 122519735.951679

DPLL1, freq ppb is -122519735.9517, reg_val is 0x1f5d741306eb

ptp4l[46048.771]: master offset   65848613 s2 freq +122519736 path delay
  -53775

physical port 2, system_port_id 4

ptp4l[46048.802]: clockcheck: clock jumped backward or running slower than
expected!

ptp4l[46048.816]: offset is 80116551, local_ts is 1662006678898571405,
weight is 1.000000

ptp4l[46048.816]: s->count-->1

ptp4l[46048.816]: s->last_freq 122519735.951679

ptp4l[46048.816]: master offset   80116551 s0 freq +122519736 path delay
  -53775

ptp4l[46048.878]: offset is 80322322, local_ts is 1662006678961277221,
weight is 1.000000

ptp4l[46048.878]: case 1

ptp4l[46048.878]: localdiff is 0.062769, s->ki is 0.098963

ptp4l[46048.878]: freq_est_interval is 0.161676

ptp4l[46048.878]: s->last_freq 122519735.951679

ptp4l[46048.878]: master offset   80322322 s0 freq +122519736 path delay
  -53775

ptp4l[46048.940]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46048.940]: offset is 80522410, local_ts is 1662006679023986055,
weight is 1.000000

ptp4l[46048.940]: s->count-->1

ptp4l[46048.940]: s->last_freq 122519735.951679

ptp4l[46048.940]: master offset   80522410 s0 freq +122519736 path delay
  -45074

ptp4l[46049.004]: offset is 80719262, local_ts is 1662006679086691447,
weight is 1.000000

ptp4l[46049.004]: case 1

ptp4l[46049.004]: localdiff is 0.062768, s->ki is 0.098963

ptp4l[46049.004]: freq_est_interval is 0.161676

ptp4l[46049.004]: s->last_freq 122519735.951679

ptp4l[46049.004]: master offset   80719262 s0 freq +122519736 path delay
  -36374

ptp4l[46049.066]: offset is 80930828, local_ts is 1662006679149394357,
weight is 1.000000

ptp4l[46049.066]: case 1

ptp4l[46049.066]: localdiff is 0.125534, s->ki is 0.098963

ptp4l[46049.066]: freq_est_interval is 0.161676

ptp4l[46049.066]: s->last_freq 122519735.951679

ptp4l[46049.066]: master offset   80930828 s0 freq +122519736 path delay
  -45074

ptp4l[46049.128]: offset is 81140892, local_ts is 1662006679212104466,
weight is 1.000000

ptp4l[46049.128]: case 1

ptp4l[46049.128]: localdiff is 0.188307, s->ki is 0.098963

ptp4l[46049.128]: freq_est_interval is 0.161676

ptp4l[46049.128]: s->drift is 19856538.841329

ptp4l[46049.128]: servo->max_frequency is 900000000.000000

ptp4l[46049.128]: s->drift is 19856538.841329

ptp4l[46049.128]: 2: servo->max_frequency is 900000000.000000

ptp4l[46049.128]: case 1 ppb is 19856538.841329

ptp4l[46049.128]: s->last_freq 19856538.841329

DPLL1, freq ppb is -19856538.8413, reg_val is 0x5155170ef6d

ptp4l[46051.230]: master offset   81140892 s2 freq +19856539 path delay
  -45074

ptp4l[46051.230]: clockcheck: clock jumped backward or running slower than
expected!

ptp4l[46051.230]: offset is 81347996, local_ts is 1662006679274811615,
weight is 1.000000

ptp4l[46051.230]: s->count-->1

ptp4l[46051.230]: s->last_freq 19856538.841329

ptp4l[46051.230]: master offset   81347996 s0 freq +19856539 path delay
  -45074

physical port 2, system_port_id 4

ptp4l[46051.316]: offset is 88389305, local_ts is 1662006681406843233,
weight is 1.000000

ptp4l[46051.316]: case 1

ptp4l[46051.316]: localdiff is 2.134164, s->ki is 0.098963

ptp4l[46051.316]: freq_est_interval is 0.161676

ptp4l[46051.316]: s->drift is 23093588.853196

ptp4l[46051.316]: servo->max_frequency is 900000000.000000

ptp4l[46051.316]: s->drift is 23093588.853196

ptp4l[46051.316]: 2: servo->max_frequency is 900000000.000000

ptp4l[46051.316]: case 1 ppb is 23093588.853196

ptp4l[46051.316]: s->last_freq 23093588.853196

DPLL1, freq ppb is -23093588.8532, reg_val is 0x5e97620df2b

ptp4l[46053.418]: master offset   88389305 s2 freq +23093589 path delay
  -54650

ptp4l[46053.418]: offset is 88594408, local_ts is 1662006681469548380,
weight is 1.000000

ptp4l[46053.418]: s->ki 0.098963, offset 88594408, weight 1.000000

ptp4l[46053.418]: s->kp 1.608178, offset 88594408, weight 1.000000,
s->drift 23093588.853196, ki_term 8767576.665468

ptp4l[46053.418]: case 2 ppb 174336716.542815

ptp4l[46053.418]: servo->max_frequency is 900000000.000000

ptp4l[46053.418]: 2:case 2 ppb 174336716.542815

ptp4l[46053.418]: 2: servo->max_frequency is 900000000.000000

ptp4l[46053.418]: s->last_freq 174336716.542815

DPLL1, freq ppb is -174336716.5428, reg_val is 0x2ca154c00b1d

ptp4l[46055.521]: master offset   88594408 s2 freq +174336717 path delay
  -54650

physical port 2, system_port_id 4

ptp4l[46055.551]: clockcheck: clock jumped backward or running slower than
expected!

ptp4l[46055.566]: offset is 102459626, local_ts is 1662006685670913528,
weight is 1.000000

ptp4l[46055.566]: s->count-->1

ptp4l[46055.566]: s->last_freq 174336716.542815

ptp4l[46055.566]: master offset  102459626 s0 freq +174336717 path delay
  -54650

ptp4l[46055.629]: offset is 102666607, local_ts is 1662006685733620554,
weight is 1.000000

ptp4l[46055.629]: case 1

ptp4l[46055.629]: localdiff is 0.062770, s->ki is 0.098963

ptp4l[46055.629]: freq_est_interval is 0.161676

ptp4l[46055.629]: s->last_freq 174336716.542815

ptp4l[46055.629]: master offset  102666607 s0 freq +174336717 path delay
  -54650

ptp4l[46055.691]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46055.691]: offset is 102835819, local_ts is 1662006685796324673,
weight is 1.000000

ptp4l[46055.691]: s->count-->1

ptp4l[46055.691]: s->last_freq 174336716.542815

ptp4l[46055.691]: master offset  102835819 s0 freq +174336717 path delay
  -19788

ptp4l[46055.753]: offset is 103063740, local_ts is 1662006685859033546,
weight is 1.000000

ptp4l[46055.753]: case 1

ptp4l[46055.753]: localdiff is 0.062772, s->ki is 0.098963

ptp4l[46055.753]: freq_est_interval is 0.161676

ptp4l[46055.753]: s->last_freq 174336716.542815

ptp4l[46055.753]: master offset  103063740 s0 freq +174336717 path delay
  -38881

ptp4l[46055.816]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46055.816]: offset is 103272193, local_ts is 1662006685921744706,
weight is 1.000000

ptp4l[46055.816]: s->count-->1

ptp4l[46055.816]: s->last_freq 174336716.542815

ptp4l[46055.816]: master offset  103272193 s0 freq +174336717 path delay
  -38881

ptp4l[46055.879]: offset is 103480491, local_ts is 1662006685984447021,
weight is 1.000000

ptp4l[46055.879]: case 1

ptp4l[46055.879]: localdiff is 0.062765, s->ki is 0.098963

ptp4l[46055.879]: freq_est_interval is 0.161676

ptp4l[46055.879]: s->last_freq 174336716.542815

ptp4l[46055.879]: master offset  103480491 s0 freq +174336717 path delay
  -42041

ptp4l[46055.941]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46055.941]: offset is 103686157, local_ts is 1662006686047152732,
weight is 1.000000

ptp4l[46055.941]: s->count-->1

ptp4l[46055.941]: s->last_freq 174336716.542815

ptp4l[46055.941]: master offset  103686157 s0 freq +174336717 path delay
  -42041

ptp4l[46056.003]: offset is 103897943, local_ts is 1662006686109862424,
weight is 1.000000

ptp4l[46056.003]: case 1

ptp4l[46056.003]: localdiff is 0.062772, s->ki is 0.098963

ptp4l[46056.003]: freq_est_interval is 0.161676

ptp4l[46056.003]: s->last_freq 174336716.542815

ptp4l[46056.003]: master offset  103897943 s0 freq +174336717 path delay
  -44180

ptp4l[46056.066]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46056.066]: offset is 104104340, local_ts is 1662006686172568866,
weight is 1.000000

ptp4l[46056.066]: s->count-->1

ptp4l[46056.066]: s->last_freq 174336716.542815

ptp4l[46056.066]: master offset  104104340 s0 freq +174336717 path delay
  -44180

ptp4l[46056.128]: offset is 104305821, local_ts is 1662006686235272326,
weight is 1.000000

ptp4l[46056.128]: case 1

ptp4l[46056.128]: localdiff is 0.062766, s->ki is 0.098963

ptp4l[46056.128]: freq_est_interval is 0.161676

ptp4l[46056.128]: s->last_freq 174336716.542815

ptp4l[46056.128]: master offset  104305821 s0 freq +174336717 path delay
  -42041

ptp4l[46056.190]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46056.190]: offset is 104515581, local_ts is 1662006686297982131,
weight is 1.000000

ptp4l[46056.190]: s->count-->1

ptp4l[46056.190]: s->last_freq 174336716.542815

ptp4l[46056.190]: master offset  104515581 s0 freq +174336717 path delay
  -42041

ptp4l[46056.254]: offset is 104723462, local_ts is 1662006686360690056,
weight is 1.000000

ptp4l[46056.254]: case 1

ptp4l[46056.254]: localdiff is 0.062771, s->ki is 0.098963

ptp4l[46056.254]: freq_est_interval is 0.161676

ptp4l[46056.254]: s->last_freq 174336716.542815

ptp4l[46056.254]: master offset  104723462 s0 freq +174336717 path delay
  -42041

ptp4l[46056.316]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46056.316]: offset is 104918985, local_ts is 1662006686423394788,
weight is 1.000000

ptp4l[46056.316]: s->count-->1

ptp4l[46056.316]: s->last_freq 174336716.542815

ptp4l[46056.316]: master offset  104918985 s0 freq +174336717 path delay
  -32877

ptp4l[46056.378]: offset is 105113290, local_ts is 1662006686486101502,
weight is 1.000000

ptp4l[46056.378]: case 1

ptp4l[46056.378]: localdiff is 0.062769, s->ki is 0.098963

ptp4l[46056.378]: freq_est_interval is 0.161676

ptp4l[46056.378]: s->last_freq 174336716.542815

ptp4l[46056.378]: master offset  105113290 s0 freq +174336717 path delay
  -20513

ptp4l[46056.441]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46056.441]: offset is 105322488, local_ts is 1662006686548810540,
weight is 1.000000

ptp4l[46056.441]: s->count-->1

ptp4l[46056.441]: s->last_freq 174336716.542815

ptp4l[46056.441]: master offset  105322488 s0 freq +174336717 path delay
  -20513

ptp4l[46056.503]: offset is 105517086, local_ts is 1662006686611516563,
weight is 1.000000

ptp4l[46056.503]: case 1

ptp4l[46056.503]: localdiff is 0.062769, s->ki is 0.098963

ptp4l[46056.503]: freq_est_interval is 0.161676

ptp4l[46056.503]: s->last_freq 174336716.542815

ptp4l[46056.503]: master offset  105517086 s0 freq +174336717 path delay
  -9133

ptp4l[46056.566]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46056.566]: offset is 105720658, local_ts is 1662006686674220180,
weight is 1.000000

ptp4l[46056.566]: s->count-->1

ptp4l[46056.566]: s->last_freq 174336716.542815

ptp4l[46056.566]: master offset  105720658 s0 freq +174336717 path delay
  -9133

ptp4l[46056.629]: offset is 105931235, local_ts is 1662006686736930801,
weight is 1.000000

ptp4l[46056.629]: case 1

ptp4l[46056.629]: localdiff is 0.062773, s->ki is 0.098963

ptp4l[46056.629]: freq_est_interval is 0.161676

ptp4l[46056.629]: s->last_freq 174336716.542815

ptp4l[46056.629]: master offset  105931235 s0 freq +174336717 path delay
  -9133

ptp4l[46056.691]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46056.691]: offset is 106149187, local_ts is 1662006686799638092,
weight is 1.000000

ptp4l[46056.691]: s->count-->1

ptp4l[46056.691]: s->last_freq 174336716.542815

ptp4l[46056.691]: master offset  106149187 s0 freq +174336717 path delay
  -19634

ptp4l[46056.753]: offset is 106353581, local_ts is 1662006686862342531,
weight is 1.000000

ptp4l[46056.753]: case 1

ptp4l[46056.753]: localdiff is 0.062767, s->ki is 0.098963

ptp4l[46056.753]: freq_est_interval is 0.161676

ptp4l[46056.753]: s->last_freq 174336716.542815

ptp4l[46056.753]: master offset  106353581 s0 freq +174336717 path delay
  -19634

ptp4l[46056.816]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46056.816]: offset is 106565901, local_ts is 1662006686925050403,
weight is 1.000000

ptp4l[46056.816]: s->count-->1

ptp4l[46056.816]: s->last_freq 174336716.542815

ptp4l[46056.816]: master offset  106565901 s0 freq +174336717 path delay
  -24127

ptp4l[46056.878]: offset is 106774788, local_ts is 1662006686987759335,
weight is 1.000000

ptp4l[46056.878]: case 1

ptp4l[46056.878]: localdiff is 0.062772, s->ki is 0.098963

ptp4l[46056.878]: freq_est_interval is 0.161676

ptp4l[46056.878]: s->last_freq 174336716.542815

ptp4l[46056.878]: master offset  106774788 s0 freq +174336717 path delay
  -24127

ptp4l[46056.941]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46056.941]: offset is 106976403, local_ts is 1662006687050464921,
weight is 1.000000

ptp4l[46056.941]: s->count-->1

ptp4l[46056.941]: s->last_freq 174336716.542815

ptp4l[46056.941]: master offset  106976403 s0 freq +174336717 path delay
  -20201

ptp4l[46057.004]: offset is 107181023, local_ts is 1662006687113169381,
weight is 1.000000

ptp4l[46057.004]: case 1

ptp4l[46057.004]: localdiff is 0.062767, s->ki is 0.098963

ptp4l[46057.004]: freq_est_interval is 0.161676

ptp4l[46057.004]: s->last_freq 174336716.542815

ptp4l[46057.004]: master offset  107181023 s0 freq +174336717 path delay
  -20201

ptp4l[46057.066]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46057.066]: offset is 107391093, local_ts is 1662006687175879495,
weight is 1.000000

ptp4l[46057.066]: s->count-->1

ptp4l[46057.066]: s->last_freq 174336716.542815

ptp4l[46057.066]: master offset  107391093 s0 freq +174336717 path delay
  -20201

ptp4l[46057.128]: offset is 107597987, local_ts is 1662006687238586434,
weight is 1.000000

ptp4l[46057.128]: case 1

ptp4l[46057.128]: localdiff is 0.062770, s->ki is 0.098963

ptp4l[46057.128]: freq_est_interval is 0.161676

ptp4l[46057.128]: s->last_freq 174336716.542815

ptp4l[46057.128]: master offset  107597987 s0 freq +174336717 path delay
  -20201

ptp4l[46057.191]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46057.191]: offset is 107801951, local_ts is 1662006687301290443,
weight is 1.000000

ptp4l[46057.191]: s->count-->1

ptp4l[46057.191]: s->last_freq 174336716.542815

ptp4l[46057.191]: master offset  107801951 s0 freq +174336717 path delay
  -20201

ptp4l[46057.254]: offset is 108010743, local_ts is 1662006687363999075,
weight is 1.000000

ptp4l[46057.254]: case 1

ptp4l[46057.254]: localdiff is 0.062771, s->ki is 0.098963

ptp4l[46057.254]: freq_est_interval is 0.161676

ptp4l[46057.254]: s->last_freq 174336716.542815

ptp4l[46057.254]: master offset  108010743 s0 freq +174336717 path delay
  -20201

ptp4l[46057.316]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46057.316]: offset is 108219095, local_ts is 1662006687426707472,
weight is 1.000000

ptp4l[46057.316]: s->count-->1

ptp4l[46057.316]: s->last_freq 174336716.542815

ptp4l[46057.316]: master offset  108219095 s0 freq +174336717 path delay
  -20201

ptp4l[46057.378]: offset is 108427506, local_ts is 1662006687489412550,
weight is 1.000000

ptp4l[46057.378]: case 1

ptp4l[46057.378]: localdiff is 0.062768, s->ki is 0.098963

ptp4l[46057.378]: freq_est_interval is 0.161676

ptp4l[46057.378]: s->last_freq 174336716.542815

ptp4l[46057.378]: master offset  108427506 s0 freq +174336717 path delay
  -23579

ptp4l[46057.441]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46057.441]: offset is 108633219, local_ts is 1662006687552118307,
weight is 1.000000

ptp4l[46057.441]: s->count-->1

ptp4l[46057.441]: s->last_freq 174336716.542815

ptp4l[46057.441]: master offset  108633219 s0 freq +174336717 path delay
  -23579

ptp4l[46057.503]: offset is 108842914, local_ts is 1662006687614828047,
weight is 1.000000

ptp4l[46057.503]: case 1

ptp4l[46057.503]: localdiff is 0.062772, s->ki is 0.098963

ptp4l[46057.503]: freq_est_interval is 0.161676

ptp4l[46057.503]: s->last_freq 174336716.542815

ptp4l[46057.503]: master offset  108842914 s0 freq +174336717 path delay
  -23579

ptp4l[46057.565]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46057.565]: offset is 109046349, local_ts is 1662006687677534369,
weight is 1.000000

ptp4l[46057.565]: s->count-->1

ptp4l[46057.565]: s->last_freq 174336716.542815

ptp4l[46057.565]: master offset  109046349 s0 freq +174336717 path delay
  -20532

ptp4l[46057.629]: offset is 109250032, local_ts is 1662006687740238097,
weight is 1.000000

ptp4l[46057.629]: case 1

ptp4l[46057.629]: localdiff is 0.062766, s->ki is 0.098963

ptp4l[46057.629]: freq_est_interval is 0.161676

ptp4l[46057.629]: s->last_freq 174336716.542815

ptp4l[46057.629]: master offset  109250032 s0 freq +174336717 path delay
  -20532

ptp4l[46057.691]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46057.691]: offset is 109459947, local_ts is 1662006687802948057,
weight is 1.000000

ptp4l[46057.691]: s->count-->1

ptp4l[46057.691]: s->last_freq 174336716.542815

ptp4l[46057.691]: master offset  109459947 s0 freq +174336717 path delay
  -20532

ptp4l[46057.753]: offset is 109680768, local_ts is 1662006687865656070,
weight is 1.000000

ptp4l[46057.753]: case 1

ptp4l[46057.753]: localdiff is 0.062771, s->ki is 0.098963

ptp4l[46057.753]: freq_est_interval is 0.161676

ptp4l[46057.753]: s->last_freq 174336716.542815

ptp4l[46057.753]: master offset  109680768 s0 freq +174336717 path delay
  -33385

ptp4l[46057.816]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46057.816]: offset is 109885570, local_ts is 1662006687928360712,
weight is 1.000000

ptp4l[46057.816]: s->count-->1

ptp4l[46057.816]: s->last_freq 174336716.542815

ptp4l[46057.816]: master offset  109885570 s0 freq +174336717 path delay
  -33385

ptp4l[46057.878]: offset is 110110931, local_ts is 1662006687991067622,
weight is 1.000000

ptp4l[46057.878]: case 1

ptp4l[46057.878]: localdiff is 0.062770, s->ki is 0.098963

ptp4l[46057.878]: freq_est_interval is 0.161676

ptp4l[46057.878]: s->last_freq 174336716.542815

ptp4l[46057.878]: master offset  110110931 s0 freq +174336717 path delay
  -51880

ptp4l[46057.941]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46057.941]: offset is 110320238, local_ts is 1662006688053776974,
weight is 1.000000

ptp4l[46057.941]: s->count-->1

ptp4l[46057.941]: s->last_freq 174336716.542815

ptp4l[46057.941]: master offset  110320238 s0 freq +174336717 path delay
  -51880

ptp4l[46058.004]: offset is 110510337, local_ts is 1662006688116482987,
weight is 1.000000

ptp4l[46058.004]: case 1

ptp4l[46058.004]: localdiff is 0.062769, s->ki is 0.098963

ptp4l[46058.004]: freq_est_interval is 0.161676

ptp4l[46058.004]: s->last_freq 174336716.542815

ptp4l[46058.004]: master offset  110510337 s0 freq +174336717 path delay
  -36011

ptp4l[46058.066]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46058.066]: offset is 110713931, local_ts is 1662006688179186626,
weight is 1.000000

ptp4l[46058.066]: s->count-->1

ptp4l[46058.066]: s->last_freq 174336716.542815

ptp4l[46058.066]: master offset  110713931 s0 freq +174336717 path delay
  -36011

ptp4l[46058.128]: offset is 110924424, local_ts is 1662006688241896959,
weight is 1.000000

ptp4l[46058.128]: case 1

ptp4l[46058.128]: localdiff is 0.062773, s->ki is 0.098963

ptp4l[46058.128]: freq_est_interval is 0.161676

ptp4l[46058.128]: s->last_freq 174336716.542815

ptp4l[46058.128]: master offset  110924424 s0 freq +174336717 path delay
  -36011

ptp4l[46058.191]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46058.191]: offset is 111131764, local_ts is 1662006688304604344,
weight is 1.000000

ptp4l[46058.191]: s->count-->1

ptp4l[46058.191]: s->last_freq 174336716.542815

ptp4l[46058.191]: master offset  111131764 s0 freq +174336717 path delay
  -36011

ptp4l[46058.253]: offset is 111351926, local_ts is 1662006688367308681,
weight is 1.000000

ptp4l[46058.253]: case 1

ptp4l[46058.253]: localdiff is 0.062767, s->ki is 0.098963

ptp4l[46058.253]: freq_est_interval is 0.161676

ptp4l[46058.253]: s->last_freq 174336716.542815

ptp4l[46058.253]: master offset  111351926 s0 freq +174336717 path delay
  -51880

ptp4l[46058.316]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46058.316]: offset is 111559692, local_ts is 1662006688430016492,
weight is 1.000000

ptp4l[46058.316]: s->count-->1

ptp4l[46058.316]: s->last_freq 174336716.542815

ptp4l[46058.316]: master offset  111559692 s0 freq +174336717 path delay
  -51880

ptp4l[46058.379]: offset is 111768404, local_ts is 1662006688492725044,
weight is 1.000000

ptp4l[46058.379]: case 1

ptp4l[46058.379]: localdiff is 0.062771, s->ki is 0.098963

ptp4l[46058.379]: freq_est_interval is 0.161676

ptp4l[46058.379]: s->last_freq 174336716.542815

ptp4l[46058.379]: master offset  111768404 s0 freq +174336717 path delay
  -51880

ptp4l[46058.441]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46058.441]: offset is 111973866, local_ts is 1662006688555430551,
weight is 1.000000

ptp4l[46058.441]: s->count-->1

ptp4l[46058.441]: s->last_freq 174336716.542815

ptp4l[46058.441]: master offset  111973866 s0 freq +174336717 path delay
  -51880

ptp4l[46058.503]: offset is 112178723, local_ts is 1662006688618138525,
weight is 1.000000

ptp4l[46058.503]: case 1

ptp4l[46058.503]: localdiff is 0.062771, s->ki is 0.098963

ptp4l[46058.503]: freq_est_interval is 0.161676

ptp4l[46058.503]: s->last_freq 174336716.542815

ptp4l[46058.503]: master offset  112178723 s0 freq +174336717 path delay
  -51880

ptp4l[46058.566]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46058.566]: offset is 112388645, local_ts is 1662006688680845419,
weight is 1.000000

ptp4l[46058.566]: s->count-->1

ptp4l[46058.566]: s->last_freq 174336716.542815

ptp4l[46058.566]: master offset  112388645 s0 freq +174336717 path delay
  -51880

ptp4l[46058.629]: offset is 112588705, local_ts is 1662006688743552373,
weight is 1.000000

ptp4l[46058.629]: case 1

ptp4l[46058.629]: localdiff is 0.062770, s->ki is 0.098963

ptp4l[46058.629]: freq_est_interval is 0.161676

ptp4l[46058.629]: s->last_freq 174336716.542815

ptp4l[46058.629]: master offset  112588705 s0 freq +174336717 path delay
  -45031

ptp4l[46058.691]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46058.691]: offset is 112792705, local_ts is 1662006688806256213,
weight is 1.000000

ptp4l[46058.691]: s->count-->1

ptp4l[46058.691]: s->last_freq 174336716.542815

ptp4l[46058.691]: master offset  112792705 s0 freq +174336717 path delay
  -45031

ptp4l[46058.753]: offset is 113001612, local_ts is 1662006688868965165,
weight is 1.000000

ptp4l[46058.753]: case 1

ptp4l[46058.753]: localdiff is 0.062772, s->ki is 0.098963

ptp4l[46058.753]: freq_est_interval is 0.161676

ptp4l[46058.753]: s->last_freq 174336716.542815

ptp4l[46058.753]: master offset  113001612 s0 freq +174336717 path delay
  -45031

ptp4l[46058.816]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46058.816]: offset is 113194004, local_ts is 1662006688931673622,
weight is 1.000000

ptp4l[46058.816]: s->count-->1

ptp4l[46058.816]: s->last_freq 174336716.542815

ptp4l[46058.816]: master offset  113194004 s0 freq +174336717 path delay
  -29011

ptp4l[46058.878]: offset is 113399105, local_ts is 1662006688994378768,
weight is 1.000000

ptp4l[46058.878]: case 1

ptp4l[46058.878]: localdiff is 0.062768, s->ki is 0.098963

ptp4l[46058.878]: freq_est_interval is 0.161676

ptp4l[46058.878]: s->last_freq 174336716.542815

ptp4l[46058.878]: master offset  113399105 s0 freq +174336717 path delay
  -29011

ptp4l[46058.940]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46058.940]: offset is 113622403, local_ts is 1662006689057084591,
weight is 1.000000

ptp4l[46058.940]: s->count-->1

ptp4l[46058.940]: s->last_freq 174336716.542815

ptp4l[46058.940]: master offset  113622403 s0 freq +174336717 path delay
  -46531

ptp4l[46059.004]: offset is 113831999, local_ts is 1662006689119794027,
weight is 1.000000

ptp4l[46059.004]: case 1

ptp4l[46059.004]: localdiff is 0.062772, s->ki is 0.098963

ptp4l[46059.004]: freq_est_interval is 0.161676

ptp4l[46059.004]: s->last_freq 174336716.542815

ptp4l[46059.004]: master offset  113831999 s0 freq +174336717 path delay
  -46531

ptp4l[46059.066]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46059.066]: offset is 114059493, local_ts is 1662006689182500441,
weight is 1.000000

ptp4l[46059.066]: s->count-->1

ptp4l[46059.066]: s->last_freq 174336716.542815

ptp4l[46059.066]: master offset  114059493 s0 freq +174336717 path delay
  -67655

ptp4l[46059.128]: offset is 114263079, local_ts is 1662006689245204072,
weight is 1.000000

ptp4l[46059.128]: case 1

ptp4l[46059.128]: localdiff is 0.062766, s->ki is 0.098963

ptp4l[46059.128]: freq_est_interval is 0.161676

ptp4l[46059.128]: s->last_freq 174336716.542815

ptp4l[46059.128]: master offset  114263079 s0 freq +174336717 path delay
  -67655

ptp4l[46059.191]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46059.191]: offset is 114472943, local_ts is 1662006689307913981,
weight is 1.000000

ptp4l[46059.191]: s->count-->1

ptp4l[46059.191]: s->last_freq 174336716.542815

ptp4l[46059.191]: master offset  114472943 s0 freq +174336717 path delay
  -67655

ptp4l[46059.253]: offset is 114669848, local_ts is 1662006689370621597,
weight is 1.000000

ptp4l[46059.253]: case 1

ptp4l[46059.253]: localdiff is 0.062770, s->ki is 0.098963

ptp4l[46059.253]: freq_est_interval is 0.161676

ptp4l[46059.253]: s->last_freq 174336716.542815

ptp4l[46059.253]: master offset  114669848 s0 freq +174336717 path delay
  -56784

ptp4l[46059.316]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46059.316]: offset is 114874438, local_ts is 1662006689433326232,
weight is 1.000000

ptp4l[46059.316]: s->count-->1

ptp4l[46059.316]: s->last_freq 174336716.542815

ptp4l[46059.316]: master offset  114874438 s0 freq +174336717 path delay
  -56784

ptp4l[46059.379]: offset is 115081003, local_ts is 1662006689496032998,
weight is 1.000000

ptp4l[46059.379]: case 1

ptp4l[46059.379]: localdiff is 0.062769, s->ki is 0.098963

ptp4l[46059.379]: freq_est_interval is 0.161676

ptp4l[46059.379]: s->last_freq 174336716.542815

ptp4l[46059.379]: master offset  115081003 s0 freq +174336717 path delay
  -56628

ptp4l[46059.441]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46059.441]: offset is 115290141, local_ts is 1662006689558742180,
weight is 1.000000

ptp4l[46059.441]: s->count-->1

ptp4l[46059.441]: s->last_freq 174336716.542815

ptp4l[46059.441]: master offset  115290141 s0 freq +174336717 path delay
  -56628

ptp4l[46059.503]: offset is 115496037, local_ts is 1662006689621448121,
weight is 1.000000

ptp4l[46059.503]: case 1

ptp4l[46059.503]: localdiff is 0.062769, s->ki is 0.098963

ptp4l[46059.503]: freq_est_interval is 0.161676

ptp4l[46059.503]: s->last_freq 174336716.542815

ptp4l[46059.503]: master offset  115496037 s0 freq +174336717 path delay
  -56628

ptp4l[46059.566]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46059.566]: offset is 115696953, local_ts is 1662006689684151597,
weight is 1.000000

ptp4l[46059.566]: s->count-->1

ptp4l[46059.566]: s->last_freq 174336716.542815

ptp4l[46059.566]: master offset  115696953 s0 freq +174336717 path delay
  -53908

ptp4l[46059.628]: offset is 115907437, local_ts is 1662006689746862126,
weight is 1.000000

ptp4l[46059.628]: case 1

ptp4l[46059.628]: localdiff is 0.062773, s->ki is 0.098963

ptp4l[46059.628]: freq_est_interval is 0.161676

ptp4l[46059.628]: s->last_freq 174336716.542815

ptp4l[46059.628]: master offset  115907437 s0 freq +174336717 path delay
  -53908

ptp4l[46059.691]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46059.691]: offset is 116114766, local_ts is 1662006689809569500,
weight is 1.000000

ptp4l[46059.691]: s->count-->1

ptp4l[46059.691]: s->last_freq 174336716.542815

ptp4l[46059.691]: master offset  116114766 s0 freq +174336717 path delay
  -53908

ptp4l[46059.754]: offset is 116301441, local_ts is 1662006689872273833,
weight is 1.000000

ptp4l[46059.754]: case 1

ptp4l[46059.754]: localdiff is 0.062767, s->ki is 0.098963

ptp4l[46059.754]: freq_est_interval is 0.161676

ptp4l[46059.754]: s->last_freq 174336716.542815

ptp4l[46059.754]: master offset  116301441 s0 freq +174336717 path delay
  -36295

ptp4l[46059.816]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46059.816]: offset is 116527455, local_ts is 1662006689934981546,
weight is 1.000000

ptp4l[46059.816]: s->count-->1

ptp4l[46059.816]: s->last_freq 174336716.542815

ptp4l[46059.816]: master offset  116527455 s0 freq +174336717 path delay
  -54436

ptp4l[46059.878]: offset is 116736226, local_ts is 1662006689997690361,
weight is 1.000000

ptp4l[46059.878]: case 1

ptp4l[46059.878]: localdiff is 0.062772, s->ki is 0.098963

ptp4l[46059.878]: freq_est_interval is 0.161676

ptp4l[46059.878]: s->last_freq 174336716.542815

ptp4l[46059.878]: master offset  116736226 s0 freq +174336717 path delay
  -54436

ptp4l[46059.941]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46059.941]: offset is 116941670, local_ts is 1662006690060395850,
weight is 1.000000

ptp4l[46059.941]: s->count-->1

ptp4l[46059.941]: s->last_freq 174336716.542815

ptp4l[46059.941]: master offset  116941670 s0 freq +174336717 path delay
  -54436

ptp4l[46060.004]: offset is 117146353, local_ts is 1662006690123100578,
weight is 1.000000

ptp4l[46060.004]: case 1

ptp4l[46060.004]: localdiff is 0.062767, s->ki is 0.098963

ptp4l[46060.004]: freq_est_interval is 0.161676

ptp4l[46060.004]: s->last_freq 174336716.542815

ptp4l[46060.004]: master offset  117146353 s0 freq +174336717 path delay
  -54436

ptp4l[46060.066]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46060.066]: offset is 117356312, local_ts is 1662006690185810582,
weight is 1.000000

ptp4l[46060.066]: s->count-->1

ptp4l[46060.066]: s->last_freq 174336716.542815

ptp4l[46060.066]: master offset  117356312 s0 freq +174336717 path delay
  -54436

ptp4l[46060.128]: offset is 117563084, local_ts is 1662006690248517194,
weight is 1.000000

ptp4l[46060.128]: case 1

ptp4l[46060.128]: localdiff is 0.062769, s->ki is 0.098963

ptp4l[46060.128]: freq_est_interval is 0.161676

ptp4l[46060.128]: s->last_freq 174336716.542815

ptp4l[46060.128]: master offset  117563084 s0 freq +174336717 path delay
  -54436

ptp4l[46060.191]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46060.191]: offset is 117766945, local_ts is 1662006690311221100,
weight is 1.000000

ptp4l[46060.191]: s->count-->1

ptp4l[46060.191]: s->last_freq 174336716.542815

ptp4l[46060.191]: master offset  117766945 s0 freq +174336717 path delay
  -54436

ptp4l[46060.253]: offset is 117979481, local_ts is 1662006690373929989,
weight is 1.000000

ptp4l[46060.253]: case 1

ptp4l[46060.253]: localdiff is 0.062772, s->ki is 0.098963

ptp4l[46060.253]: freq_est_interval is 0.161676

ptp4l[46060.253]: s->last_freq 174336716.542815

ptp4l[46060.253]: master offset  117979481 s0 freq +174336717 path delay
  -58127

ptp4l[46060.315]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46060.315]: offset is 118187707, local_ts is 1662006690436638260,
weight is 1.000000

ptp4l[46060.315]: s->count-->1

ptp4l[46060.315]: s->last_freq 174336716.542815

ptp4l[46060.315]: master offset  118187707 s0 freq +174336717 path delay
  -58127

ptp4l[46060.379]: offset is 118388901, local_ts is 1662006690499342985,
weight is 1.000000

ptp4l[46060.379]: case 1

ptp4l[46060.379]: localdiff is 0.062767, s->ki is 0.098963

ptp4l[46060.379]: freq_est_interval is 0.161676

ptp4l[46060.379]: s->last_freq 174336716.542815

ptp4l[46060.379]: master offset  118388901 s0 freq +174336717 path delay
  -54436

ptp4l[46060.441]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46060.441]: offset is 118595121, local_ts is 1662006690562048728,
weight is 1.000000

ptp4l[46060.441]: s->count-->1

ptp4l[46060.441]: s->last_freq 174336716.542815

ptp4l[46060.441]: master offset  118595121 s0 freq +174336717 path delay
  -54958

ptp4l[46060.503]: offset is 118796358, local_ts is 1662006690624758302,
weight is 1.000000

ptp4l[46060.503]: case 1

ptp4l[46060.503]: localdiff is 0.062772, s->ki is 0.098963

ptp4l[46060.503]: freq_est_interval is 0.161676

ptp4l[46060.503]: s->last_freq 174336716.542815

ptp4l[46060.503]: master offset  118796358 s0 freq +174336717 path delay
  -46666

ptp4l[46060.566]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46060.566]: offset is 119009704, local_ts is 1662006690687464702,
weight is 1.000000

ptp4l[46060.566]: s->count-->1

ptp4l[46060.566]: s->last_freq 174336716.542815

ptp4l[46060.566]: master offset  119009704 s0 freq +174336717 path delay
  -53657

ptp4l[46060.628]: offset is 119213273, local_ts is 1662006690750168315,
weight is 1.000000

ptp4l[46060.628]: case 1

ptp4l[46060.628]: localdiff is 0.062766, s->ki is 0.098963

ptp4l[46060.628]: freq_est_interval is 0.161676

ptp4l[46060.628]: s->last_freq 174336716.542815

ptp4l[46060.628]: master offset  119213273 s0 freq +174336717 path delay
  -53657

ptp4l[46060.691]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46060.691]: offset is 119423206, local_ts is 1662006690812878088,
weight is 1.000000

ptp4l[46060.691]: s->count-->1

ptp4l[46060.691]: s->last_freq 174336716.542815

ptp4l[46060.691]: master offset  119423206 s0 freq +174336717 path delay
  -53657

ptp4l[46060.754]: offset is 119631013, local_ts is 1662006690875585940,
weight is 1.000000

ptp4l[46060.754]: case 1

ptp4l[46060.754]: localdiff is 0.062771, s->ki is 0.098963

ptp4l[46060.754]: freq_est_interval is 0.161676

ptp4l[46060.754]: s->last_freq 174336716.542815

ptp4l[46060.754]: master offset  119631013 s0 freq +174336717 path delay
  -53657

ptp4l[46060.816]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46060.816]: offset is 119835576, local_ts is 1662006690938290644,
weight is 1.000000

ptp4l[46060.816]: s->count-->1

ptp4l[46060.816]: s->last_freq 174336716.542815

ptp4l[46060.816]: master offset  119835576 s0 freq +174336717 path delay
  -53561

ptp4l[46060.878]: offset is 120042463, local_ts is 1662006691000997576,
weight is 1.000000

ptp4l[46060.878]: case 1

ptp4l[46060.878]: localdiff is 0.062770, s->ki is 0.098963

ptp4l[46060.878]: freq_est_interval is 0.161676

ptp4l[46060.878]: s->last_freq 174336716.542815

ptp4l[46060.878]: master offset  120042463 s0 freq +174336717 path delay
  -53561

ptp4l[46060.941]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46060.941]: offset is 120244739, local_ts is 1662006691063706792,
weight is 1.000000

ptp4l[46060.941]: s->count-->1

ptp4l[46060.941]: s->last_freq 174336716.542815

ptp4l[46060.941]: master offset  120244739 s0 freq +174336717 path delay
  -46666

ptp4l[46061.003]: offset is 120443898, local_ts is 1662006691126412491,
weight is 1.000000

ptp4l[46061.003]: case 1

ptp4l[46061.003]: localdiff is 0.062768, s->ki is 0.098963

ptp4l[46061.003]: freq_est_interval is 0.161676

ptp4l[46061.003]: s->last_freq 174336716.542815

ptp4l[46061.003]: master offset  120443898 s0 freq +174336717 path delay
  -39966

ptp4l[46061.066]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46061.066]: offset is 120655346, local_ts is 1662006691189116214,
weight is 1.000000

ptp4l[46061.066]: s->count-->1

ptp4l[46061.066]: s->last_freq 174336716.542815

ptp4l[46061.066]: master offset  120655346 s0 freq +174336717 path delay
  -47735

ptp4l[46061.129]: offset is 120864882, local_ts is 1662006691251826669,
weight is 1.000000

ptp4l[46061.129]: case 1

ptp4l[46061.129]: localdiff is 0.062773, s->ki is 0.098963

ptp4l[46061.129]: freq_est_interval is 0.161676

ptp4l[46061.129]: s->last_freq 174336716.542815

ptp4l[46061.129]: master offset  120864882 s0 freq +174336717 path delay
  -46861

ptp4l[46061.191]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46061.191]: offset is 121071747, local_ts is 1662006691314537178,
weight is 1.000000

ptp4l[46061.191]: s->count-->1

ptp4l[46061.191]: s->last_freq 174336716.542815

ptp4l[46061.191]: master offset  121071747 s0 freq +174336717 path delay
  -46539

ptp4l[46061.253]: offset is 121276047, local_ts is 1662006691377238041,
weight is 1.000000

ptp4l[46061.253]: case 1

ptp4l[46061.253]: localdiff is 0.062764, s->ki is 0.098963

ptp4l[46061.253]: freq_est_interval is 0.161676

ptp4l[46061.253]: s->last_freq 174336716.542815

ptp4l[46061.253]: master offset  121276047 s0 freq +174336717 path delay
  -46539

ptp4l[46061.316]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46061.316]: offset is 121483951, local_ts is 1662006691439945990,
weight is 1.000000

ptp4l[46061.316]: s->count-->1

ptp4l[46061.316]: s->last_freq 174336716.542815

ptp4l[46061.316]: master offset  121483951 s0 freq +174336717 path delay
  -46539

ptp4l[46061.379]: offset is 121691253, local_ts is 1662006691502654758,
weight is 1.000000

ptp4l[46061.379]: case 1

ptp4l[46061.379]: localdiff is 0.062771, s->ki is 0.098963

ptp4l[46061.379]: freq_est_interval is 0.161676

ptp4l[46061.379]: s->last_freq 174336716.542815

ptp4l[46061.379]: master offset  121691253 s0 freq +174336717 path delay
  -45118

ptp4l[46061.441]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46061.441]: offset is 121884263, local_ts is 1662006691565360213,
weight is 1.000000

ptp4l[46061.441]: s->count-->1

ptp4l[46061.441]: s->last_freq 174336716.542815

ptp4l[46061.441]: master offset  121884263 s0 freq +174336717 path delay
  -32717

ptp4l[46061.503]: offset is 122089059, local_ts is 1662006691628065054,
weight is 1.000000

ptp4l[46061.503]: case 1

ptp4l[46061.503]: localdiff is 0.062768, s->ki is 0.098963

ptp4l[46061.503]: freq_est_interval is 0.161676

ptp4l[46061.503]: s->last_freq 174336716.542815

ptp4l[46061.503]: master offset  122089059 s0 freq +174336717 path delay
  -32717

ptp4l[46061.566]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46061.566]: offset is 122299096, local_ts is 1662006691690774931,
weight is 1.000000

ptp4l[46061.566]: s->count-->1

ptp4l[46061.566]: s->last_freq 174336716.542815

ptp4l[46061.566]: master offset  122299096 s0 freq +174336717 path delay
  -32717

ptp4l[46061.628]: offset is 122518380, local_ts is 1662006691753481859,
weight is 1.000000

ptp4l[46061.628]: case 1

ptp4l[46061.628]: localdiff is 0.062770, s->ki is 0.098963

ptp4l[46061.628]: freq_est_interval is 0.161676

ptp4l[46061.628]: s->last_freq 174336716.542815

ptp4l[46061.628]: master offset  122518380 s0 freq +174336717 path delay
  -45118

ptp4l[46061.690]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46061.690]: offset is 122722399, local_ts is 1662006691816185923,
weight is 1.000000

ptp4l[46061.690]: s->count-->1

ptp4l[46061.690]: s->last_freq 174336716.542815

ptp4l[46061.690]: master offset  122722399 s0 freq +174336717 path delay
  -45118

ptp4l[46061.754]: offset is 122928237, local_ts is 1662006691878895043,
weight is 1.000000

ptp4l[46061.754]: case 1

ptp4l[46061.754]: localdiff is 0.062772, s->ki is 0.098963

ptp4l[46061.754]: freq_est_interval is 0.161676

ptp4l[46061.754]: s->last_freq 174336716.542815

ptp4l[46061.754]: master offset  122928237 s0 freq +174336717 path delay
  -41881

ptp4l[46061.816]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46061.816]: offset is 123136617, local_ts is 1662006691941603263,
weight is 1.000000

ptp4l[46061.816]: s->count-->1

ptp4l[46061.816]: s->last_freq 174336716.542815

ptp4l[46061.816]: master offset  123136617 s0 freq +174336717 path delay
  -41881

ptp4l[46061.878]: offset is 123341704, local_ts is 1662006692004308394,
weight is 1.000000

ptp4l[46061.878]: case 1

ptp4l[46061.878]: localdiff is 0.062768, s->ki is 0.098963

ptp4l[46061.878]: freq_est_interval is 0.161676

ptp4l[46061.878]: s->last_freq 174336716.542815

ptp4l[46061.878]: master offset  123341704 s0 freq +174336717 path delay
  -41881

ptp4l[46061.941]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46061.941]: offset is 123550951, local_ts is 1662006692067014333,
weight is 1.000000

ptp4l[46061.941]: s->count-->1

ptp4l[46061.941]: s->last_freq 174336716.542815

ptp4l[46061.941]: master offset  123550951 s0 freq +174336717 path delay
  -45234

ptp4l[46062.003]: offset is 123760522, local_ts is 1662006692129723949,
weight is 1.000000

ptp4l[46062.003]: case 1

ptp4l[46062.003]: localdiff is 0.062772, s->ki is 0.098963

ptp4l[46062.003]: freq_est_interval is 0.161676

ptp4l[46062.003]: s->last_freq 174336716.542815

ptp4l[46062.003]: master offset  123760522 s0 freq +174336717 path delay
  -45234

ptp4l[46062.066]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46062.066]: offset is 123969849, local_ts is 1662006692192430331,
weight is 1.000000

ptp4l[46062.066]: s->count-->1

ptp4l[46062.066]: s->last_freq 174336716.542815

ptp4l[46062.066]: master offset  123969849 s0 freq +174336717 path delay
  -48224

ptp4l[46062.129]: offset is 124173405, local_ts is 1662006692255133727,
weight is 1.000000

ptp4l[46062.129]: case 1

ptp4l[46062.129]: localdiff is 0.062766, s->ki is 0.098963

ptp4l[46062.129]: freq_est_interval is 0.161676

ptp4l[46062.129]: s->last_freq 174336716.542815

ptp4l[46062.129]: master offset  124173405 s0 freq +174336717 path delay
  -48224

ptp4l[46062.191]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46062.191]: offset is 124400429, local_ts is 1662006692317843728,
weight is 1.000000

ptp4l[46062.191]: s->count-->1

ptp4l[46062.191]: s->last_freq 174336716.542815

ptp4l[46062.191]: master offset  124400429 s0 freq +174336717 path delay
  -65292

ptp4l[46062.253]: offset is 124608169, local_ts is 1662006692380551512,
weight is 1.000000

ptp4l[46062.253]: case 1

ptp4l[46062.253]: localdiff is 0.062770, s->ki is 0.098963

ptp4l[46062.253]: freq_est_interval is 0.161676

ptp4l[46062.253]: s->last_freq 174336716.542815

ptp4l[46062.253]: master offset  124608169 s0 freq +174336717 path delay
  -65292

ptp4l[46062.316]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46062.316]: offset is 124795684, local_ts is 1662006692443256140,
weight is 1.000000

ptp4l[46062.316]: s->count-->1

ptp4l[46062.316]: s->last_freq 174336716.542815

ptp4l[46062.316]: master offset  124795684 s0 freq +174336717 path delay
  -48224

ptp4l[46062.378]: offset is 125002580, local_ts is 1662006692505962876,
weight is 1.000000

ptp4l[46062.378]: case 1

ptp4l[46062.378]: localdiff is 0.062769, s->ki is 0.098963

ptp4l[46062.378]: freq_est_interval is 0.161676

ptp4l[46062.378]: s->last_freq 174336716.542815

ptp4l[46062.378]: master offset  125002580 s0 freq +174336717 path delay
  -48224

ptp4l[46062.441]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46062.441]: offset is 125230666, local_ts is 1662006692568672095,
weight is 1.000000

ptp4l[46062.441]: s->count-->1

ptp4l[46062.441]: s->last_freq 174336716.542815

ptp4l[46062.441]: master offset  125230666 s0 freq +174336717 path delay
  -67136

ptp4l[46062.504]: offset is 125436596, local_ts is 1662006692631378070,
weight is 1.000000

ptp4l[46062.504]: case 1

ptp4l[46062.504]: localdiff is 0.062769, s->ki is 0.098963

ptp4l[46062.504]: freq_est_interval is 0.161676

ptp4l[46062.504]: s->last_freq 174336716.542815

ptp4l[46062.504]: master offset  125436596 s0 freq +174336717 path delay
  -67136

ptp4l[46062.566]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46062.566]: offset is 125640422, local_ts is 1662006692694081941,
weight is 1.000000

ptp4l[46062.566]: s->count-->1

ptp4l[46062.566]: s->last_freq 174336716.542815

ptp4l[46062.566]: master offset  125640422 s0 freq +174336717 path delay
  -67136

ptp4l[46062.628]: offset is 125838442, local_ts is 1662006692756792473,
weight is 1.000000

ptp4l[46062.628]: case 1

ptp4l[46062.628]: localdiff is 0.062773, s->ki is 0.098963

ptp4l[46062.628]: freq_est_interval is 0.161676

ptp4l[46062.628]: s->last_freq 174336716.542815

ptp4l[46062.628]: master offset  125838442 s0 freq +174336717 path delay
  -54668

ptp4l[46062.691]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46062.691]: offset is 126039854, local_ts is 1662006692819499665,
weight is 1.000000

ptp4l[46062.691]: s->count-->1

ptp4l[46062.691]: s->last_freq 174336716.542815

ptp4l[46062.691]: master offset  126039854 s0 freq +174336717 path delay
  -48728

ptp4l[46062.754]: offset is 126243987, local_ts is 1662006692882204044,
weight is 1.000000

ptp4l[46062.754]: case 1

ptp4l[46062.754]: localdiff is 0.062767, s->ki is 0.098963

ptp4l[46062.754]: freq_est_interval is 0.161676

ptp4l[46062.754]: s->last_freq 174336716.542815

ptp4l[46062.754]: master offset  126243987 s0 freq +174336717 path delay
  -48527

ptp4l[46062.816]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46062.816]: offset is 126448240, local_ts is 1662006692944912163,
weight is 1.000000

ptp4l[46062.816]: s->count-->1

ptp4l[46062.816]: s->last_freq 174336716.542815

ptp4l[46062.816]: master offset  126448240 s0 freq +174336717 path delay
  -44706

ptp4l[46062.878]: offset is 126647617, local_ts is 1662006693007621004,
weight is 1.000000

ptp4l[46062.878]: case 1

ptp4l[46062.878]: localdiff is 0.062772, s->ki is 0.098963

ptp4l[46062.878]: freq_est_interval is 0.161676

ptp4l[46062.878]: s->last_freq 174336716.542815

ptp4l[46062.878]: master offset  126647617 s0 freq +174336717 path delay
  -35287

ptp4l[46062.941]: clockcheck: clock jumped forward or running faster than
expected!

ptp4l[46062.941]: offset is 126853047, local_ts is 1662006693070326274,
weight is 1.000000

ptp4l[46062.941]: s->count-->1

ptp4l[46062.941]: s->last_freq 174336716.542815

ptp4l[46062.941]: master offset  126853047 s0 freq +174336717 path delay
  -35287

ptp4l[46063.003]: offset is 127057889, local_ts is 1662006693133031161,
weight is 1.000000

ptp4l[46063.003]: case 1

ptp4l[46063.003]: localdiff is...
 
[truncated message content]