Menu
▾
▴
[Linuxptp-users] Synchronisation troubles
|
From: Jord P. <jor...@ou...> - 2019-01-24 12:53:49
|
First, some information of my PTP Slave system
Network Adapter Brand & Model
[maintain@testpc-edm ~]$ lspci | egrep -i --color 'network|ethernet'
00:1f.6 Ethernet controller: Intel Corporation Ethernet Connection (2) I219-LM
01:00.0 Ethernet controller: Intel Corporation I210 Gigabit Network Connection (rev 03)
Hardware Network Adapter Information
[maintain@testpc-edm ~]$ lshw -class network
*-network
description: Ethernet interface
product: Ethernet Connection (2) I219-LM
vendor: Intel Corporation
physical id: 1f.6
bus info: pci@0000:00:1f.6
logical name: eno1
version: 00
serial: 10:e7:c6:2c:f0:ae
size: 1Gbit/s
capacity: 1Gbit/s
width: 32 bits
clock: 33MHz
capabilities: bus_master cap_list ethernet physical tp 10bt 10bt-fd 100bt 100bt-fd 1000bt-fd autonegotiation
configuration: autonegotiation=on broadcast=yes driver=e1000e driverversion=3.2.6-k duplex=full firmware=0.2-4 ip=10.0.3.25 latency=0 link=yes multicast=yes port=twisted pair speed=1Gbit/s
resources: irq:33 memory:90200000-9021ffff
Driver Information
[root@testpc-edm maintain]# ethtool -i eno1
driver: e1000e
version: 3.2.6-k
firmware-version: 0.2-4
expansion-rom-version:
bus-info: 0000:00:1f.6
supports-statistics: yes
supports-test: yes
supports-eeprom-access: yes
supports-register-dump: yes
supports-priv-flags: no
The following problems occur, and I can not find any reason why.
Linux PTP client output
ptp4l[105561.591]: master offset -31 s2 freq +14845 path delay 10144
ptp4l[105562.592]: master offset 42 s2 freq +14909 path delay 10144
ptp4l[105563.592]: master offset -158 s2 freq +14722 path delay 10144
ptp4l[105564.592]: master offset -253 s2 freq +14579 path delay 10120
ptp4l[105565.592]: master offset 240 s2 freq +14996 path delay 10120
ptp4l[105566.592]: master offset 378 s2 freq +15206 path delay 10105
ptp4l[105567.592]: master offset -68 s2 freq +14874 path delay 10105
ptp4l[105568.637]: clockcheck: clock jumped forward or running faster than expected!
ptp4l[105568.637]: master offset 1125899906842648 s0 freq +14874 path delay 10088
ptp4l[105568.637]: port 1: SLAVE to UNCALIBRATED on SYNCHRONIZATION_FAULT
ptp4l[105569.737]: master offset 1125899906842753 s2 freq +15026 path delay 10105
ptp4l[105569.737]: port 1: UNCALIBRATED to SLAVE on MASTER_CLOCK_SELECTED
ptp4l[105570.837]: master offset 1125899906842910 s2 freq +599999999<tel:+599999999> path delay 10105
ptp4l[105571.937]: master offset 1125899306891189 s2 freq +599999999<tel:+599999999> path delay 10105
ptp4l[105573.038]: master offset 1125898706866930 s2 freq +599999999<tel:+599999999> path delay 10105
ptp4l[105574.138]: master offset 1125898106828168 s2 freq +599999999<tel:+599999999> path delay 10117
ptp4l[105575.238]: master offset 1125897506778080 s2 freq +599999999<tel:+599999999> path delay 10135
ptp4l[105576.338]: master offset 1125896906739431 s2 freq +599999999<tel:+599999999> path delay 10179
ptp4l[105577.438]: master offset 1125896306694839 s2 freq +599999999<tel:+599999999> path delay 10179
ptp4l[105578.538]: master offset 1125895667906661 s2 freq +599999999<tel:+599999999> path delay 38789966
ptp4l[105579.638]: master offset 1125895007025543 s2 freq +599999999<tel:+599999999> path delay 99624508
ptp4l[105580.738]: master offset 1125894379406115 s2 freq +599999999<tel:+599999999> path delay 127219096
ptp4l[105581.838]: master offset 1125893779357139 s2 freq +599999999<tel:+599999999> path delay 127219096
ptp4l[105582.938]: master offset 1125893136252241 s2 freq +599999999<tel:+599999999> path delay 170285917
ptp4l[105584.039]: master offset 1125892547915715 s2 freq +599999999<tel:+599999999> path delay 158605648
ptp4l[105585.139]: master offset 1125891947892328 s2 freq +599999999<tel:+599999999> path delay 158605648
ptp4l[105586.239]: master offset 1125891347863517 s2 freq +599999999<tel:+599999999> path delay 158605648
ptp4l[105587.339]: master offset 1125890747818528 s2 freq +599999999<tel:+599999999> path delay 158605648
ptp4l[105588.439]: master offset 1125890147789520 s2 freq +599999999<tel:+599999999> path delay 158605648
ptp4l[105589.539]: master offset 1125889547743957 s2 freq +599999999<tel:+599999999> path delay 158605648
ptp4l[105590.639]: master offset 1125888947713140 s2 freq +599999999<tel:+599999999> path delay 158605648
ptp4l[105591.739]: master offset 1125888347685237 s2 freq +599999999<tel:+599999999> path delay 158605648
<… restarting the master clock node …>
ptp4l[343432.421]: selected best master clock 10e7c6.fffe.2cf0ae
ptp4l[343440.293]: selected best master clock 10e7c6.fffe.2cf0ae
ptp4l[343446.731]: selected best master clock 10e7c6.fffe.2cf0ae
ptp4l[343454.383]: selected best master clock 10e7c6.fffe.2cf0ae
ptp4l[343461.118]: selected best master clock 10e7c6.fffe.2cf0ae
ptp4l[343467.584]: selected best master clock 10e7c6.fffe.2cf0ae
ptp4l[343475.455]: selected best master clock 10e7c6.fffe.2cf0ae
ptp4l[343482.352]: port 1: new foreign master ac1f6b.fffe.89c7bc-1
ptp4l[343482.990]: selected best master clock 10e7c6.fffe.2cf0ae
ptp4l[343483.451]: clockcheck: clock jumped forward or running faster than expected!
ptp4l[343484.551]: clockcheck: clock jumped forward or running faster than expected!
ptp4l[343485.651]: clockcheck: clock jumped forward or running faster than expected!
ptp4l[343486.751]: clockcheck: clock jumped forward or running faster than expected!
ptp4l[343486.752]: selected best master clock ac1f6b.fffe.89c7bc
ptp4l[343486.752]: port 1: LISTENING to UNCALIBRATED on RS_SLAVE
ptp4l[343487.851]: master offset 146776049174<tel:146776049174> s0 freq +599999999<tel:+599999999> path delay 0
ptp4l[343487.851]: clockcheck: clock jumped forward or running faster than expected!
ptp4l[343488.951]: clockcheck: clock jumped forward or running faster than expected!
ptp4l[343488.951]: master offset 147611844516<tel:147611844516> s0 freq +599999999<tel:+599999999> path delay -78631652
ptp4l[343490.051]: clockcheck: clock jumped forward or running faster than expected!
ptp4l[343490.051]: master offset 148259645935<tel:148259645935> s0 freq +599999999<tel:+599999999> path delay -126397664
ptp4l[343491.151]: clockcheck: clock jumped forward or running faster than expected!
ptp4l[343491.151]: master offset 148859731565<tel:148859731565> s0 freq +599999999<tel:+599999999> path delay -126397664
ptp4l[343492.251]: clockcheck: clock jumped forward or running faster than expected!
ptp4l[343492.252]: master offset 149454356818<tel:149454356818> s0 freq +599999999<tel:+599999999> path delay -120962015
ptp4l[343493.352]: clockcheck: clock jumped forward or running faster than expected!
ptp4l[343493.352]: master offset 150107622662<tel:150107622662> s0 freq +599999999<tel:+599999999> path delay -174163677
ptp4l[343494.452]: clockcheck: clock jumped forward or running faster than expected!
ptp4l[343494.452]: master offset 150681082792<tel:150681082792> s0 freq +599999999<tel:+599999999> path delay -147562846
ptp4l[343495.552]: clockcheck: clock jumped forward or running faster than expected!
ptp4l[343495.552]: master offset 151307724822<tel:151307724822> s0 freq +599999999<tel:+599999999> path delay -174163677
ptp4l[343496.652]: clockcheck: clock jumped forward or running faster than expected!
ptp4l[343496.652]: master offset 151891422731<tel:151891422731> s0 freq +599999999<tel:+599999999> path delay -157819335
ptp4l[343497.752]: clockcheck: clock jumped forward or running faster than expected!
ptp4l[343497.752]: master offset 152491452871<tel:152491452871> s0 freq +599999999<tel:+599999999> path delay -157803168
ptp4l[343498.852]: clockcheck: clock jumped forward or running faster than expected!
ptp4l[343498.852]: master offset 153091458805<tel:153091458805> s0 freq +599999999<tel:+599999999> path delay -157803168
ptp4l[343498.909]: port 1: UNCALIBRATED to FAULTY on FAULT_DETECTED (FT_UNSPECIFIED)
ptp4l[343498.909]: timed out while polling for tx timestamp
ptp4l[343498.909]: increasing tx_timestamp_timeout may correct this issue, but it is likely caused by a driver bug
ptp4l[343498.909]: port 1: send delay request failed
ptp4l[343514.915]: port 1: FAULTY to LISTENING on FAULT_CLEARED
ptp4l[343515.353]: port 1: new foreign master ac1f6b.fffe.89c7bc-1
ptp4l[343515.353]: clockcheck: clock jumped forward or running faster than expected!
ptp4l[343516.453]: clockcheck: clock jumped forward or running faster than expected!
ptp4l[343517.553]: clockcheck: clock jumped forward or running faster than expected!
ptp4l[343518.653]: clockcheck: clock jumped forward or running faster than expected!
ptp4l[343519.753]: selected best master clock ac1f6b.fffe.89c7bc
ptp4l[343519.753]: port 1: LISTENING to UNCALIBRATED on RS_SLAVE
ptp4l[343519.753]: clockcheck: clock jumped forward or running faster than expected!
ptp4l[343519.753]: master offset 164492276443<tel:164492276443> s0 freq +599999999<tel:+599999999> path delay -157803168
ptp4l[343520.853]: master offset 165092320200<tel:165092320200> s0 freq +599999999<tel:+599999999> path delay -157803168
ptp4l[343520.853]: clockcheck: clock jumped forward or running faster than expected!
ptp4l[343521.953]: master offset 165717836009<tel:165717836009> s0 freq +599999999<tel:+599999999> path delay -183316741
ptp4l[343521.953]: clockcheck: clock jumped forward or running faster than expected!
ptp4l[343523.053]: clockcheck: clock jumped forward or running faster than expected!
ptp4l[343523.053]: master offset 166292384981<tel:166292384981> s0 freq +599999999<tel:+599999999> path delay -157803168
ptp4l[343524.153]: clockcheck: clock jumped forward or running faster than expected!
Etc.
or the following happens:
ptp4l[1909646.454]: master offset -65 s2 freq +16112 path delay 10118
ptp4l[1909647.454]: master offset 259 s2 freq +16417 path delay 10111
ptp4l[1909648.454]: master offset 169 s2 freq +16404 path delay 10111
ptp4l[1909649.455]: master offset -7 s2 freq +16279 path delay 10118
ptp4l[1909650.455]: master offset -125 s2 freq +16159 path delay 10129
ptp4l[1909651.455]: master offset -309 s2 freq +15937 path delay 10129
ptp4l[1909652.455]: master offset 108 s2 freq +16262 path delay 10111
ptp4l[1909653.455]: master offset 160 s2 freq +16346 path delay 10111
ptp4l[1909654.455]: master offset -48 s2 freq +16186 path delay 10118
ptp4l[1909655.458]: master offset 1125899906842400 s0 freq +16186 path delay 10118
ptp4l[1909655.458]: port 1: SLAVE to UNCALIBRATED on SYNCHRONIZATION_FAULT
ptp4l[1909655.458]: clockcheck: clock jumped forward or running faster than expected!
ptp4l[1909656.558]: master offset 1125899906842174 s2 freq +15994 path delay 10118
ptp4l[1909656.558]: port 1: UNCALIBRATED to SLAVE on MASTER_CLOCK_SELECTED
ptp4l[1909657.658]: master offset 1125899906842411 s2 freq +599999999<tel:+599999999> path delay 10118
ptp4l[1909658.758]: master offset 1125899306922955 s2 freq +599999999<tel:+599999999> path delay 10129
ptp4l[1909659.858]: master offset 1125898706875000 s2 freq +599999999<tel:+599999999> path delay 10142
ptp4l[1909660.958]: master offset 1125898106821440 s2 freq +599999999<tel:+599999999> path delay 10147
ptp4l[1909662.058]: master offset 1125897501167000 s2 freq +599999999<tel:+599999999> path delay 5611418
ptp4l[1909663.159]: master offset 1125896863142218 s2 freq +599999999<tel:+599999999> path delay 43584288
ptp4l[1909664.259]: master offset 1125896224694842 s2 freq +599999999<tel:+599999999> path delay 81987483
ptp4l[1909665.359]: master offset 1125895624647553 s2 freq +599999999<tel:+599999999> path delay 81987483
ptp4l[1909666.459]: master offset 1125895006053225 s2 freq +599999999<tel:+599999999> path delay 100537491
ptp4l[1909667.559]: master offset 1125894406025624 s2 freq +599999999<tel:+599999999> path delay 100537491
If anyone can help me out regarding what is causing these symptoms, that would be great. Struggling with it for a while now..
Kind regards,
Jord
|
