DelayResp message missing

[Mirek Sobczak]

Hello,

Now I'm trying to start ptpd in slave mode.
Only configuration which works for me a liitle bit is with transport:ethernet.

than ptpd2.status is:

Host info          :  mki4, PID 17979
Local time         :  Wed Feb 24 15:19:50 CET 2016
Kernel time        :  Wed Feb 24 14:19:50 GMT 2016
Interface          :  eth1
Preset             :  slaveonly
Transport          :  ethernet
Delay mechanism    :  E2E
Sync mode          :  ONE_STEP
PTP domain         :  0, preferred 0
Port state         :  PTP_SLAVE
Local port ID      :  00045afffe000001(unknown)/1
Best master ID     :  20b7c0fffe0028a1(unknown)/1
GM priority        :  Priority1 128, Priority2 128, clockClass 6
Time properties    :  PTP timescale, tracbl: time Y, freq Y, src: GPS(0x20)
UTC properties     :  UTC valid: Y, UTC offset: 36
Offset from Master : -0.003761210 s, mean  0.002340104 s, dev  0.000992186 s
Mean Path Delay    :  0.000000000 s, mean  0.000000000 s, dev  0.000000000 s
Clock status       :  in control
Clock correction   :  480.297 ppm, mean  500.000 ppm, dev  0.000 ppm
Message rates      :  1/s sync, 1/s delay, 1/s announce
TimingService      :  current PTP0, best PTP0, pref PTP0
TimingServices     :  total 1, avail 1, oper 1, idle 0, in_ctrl 1
Performance        :  Message RX 1/s, TX 1/s
Announce received  :  150
Sync received      :  150
DelayReq sent      :  203
DelayResp received :  0
State transitions  :  7
PTP Engine resets  :  2

Every time after 203 DelayReq ptpd2 server hangs.

Last 20 log massages:

2016-02-24 15:19:51.166008 ptpd2[17979].eth1 (debug1)    (slv)       ==> Announce message received, sequence 61568
2016-02-24 15:19:51.177595 ptpd2[17979].eth1 (debug3)    (slv) HandleAnnounce : Announce message received :
2016-02-24 15:19:51.190852 ptpd2[17979].eth1 (debug3)    (slv) Announce Message :
2016-02-24 15:19:51.204958 ptpd2[17979].eth1 (debug3)    (slv)
2016-02-24 15:19:51.217108 ptpd2[17979].eth1 (debug3)    (slv) originTimestamp :
2016-02-24 15:19:51.232693 ptpd2[17979].eth1 (debug3)    (slv) secondField  :
2016-02-24 15:19:51.246356 ptpd2[17979].eth1 (debug3)    (slv) Integer 48 :
2016-02-24 15:19:51.259807 ptpd2[17979].eth1 (debug3)    (slv) LSB : 1456323449
2016-02-24 15:19:51.276317 ptpd2[17979].eth1 (debug3)    (slv) MSB : 0
2016-02-24 15:19:51.289565 ptpd2[17979].eth1 (debug3)    (slv) nanoseconds 70651096
2016-02-24 15:19:51.302789 ptpd2[17979].eth1 (debug3)    (slv) currentUtcOffset : 36
2016-02-24 15:19:51.316115 ptpd2[17979].eth1 (debug3)    (slv) grandMasterPriority1 : 128
2016-02-24 15:19:51.328846 ptpd2[17979].eth1 (debug3)    (slv) grandMasterClockQuality :
2016-02-24 15:19:51.341954 ptpd2[17979].eth1 (debug3)    (slv) clockClass : 6
2016-02-24 15:19:51.355750 ptpd2[17979].eth1 (debug3)    (slv) clockAccuracy : 33
2016-02-24 15:19:51.369208 ptpd2[17979].eth1 (debug3)    (slv) offsetScaledLogVariance : 18465
2016-02-24 15:19:51.382053 ptpd2[17979].eth1 (debug3)    (slv) grandMasterPriority2 : 128
2016-02-24 15:19:51.394610 ptpd2[17979].eth1 (debug3)    (slv) grandMasterIdentity :
2016-02-24 15:19:51.410071 ptpd2[17979].eth1 (debug3)    (slv) ClockIdentity : 20:b7:c0:ff:fe:00:28:a1
2016-02-24 15:19:51.422821 ptpd2[17979].eth1 (debug3)    (slv) stepsRemoved : 0
2016-02-24 15:19:51.437048 ptpd2[17979].eth1 (debug3)    (slv) timeSource : 32
2016-02-24 15:19:51.449787 ptpd2[17979].eth1 (debug3)    (slv)
2016-02-24 15:19:51.461729 ptpd2[17979].eth1 (debug2)    (slv) ___ Announce: received Announce from current Master, so reset the Announce timer
2016-02-24 15:19:51.476445 ptpd2[17979].eth1 (debug3)    (slv) Timer ANNOUNCE_RECEIPT start requested at 6.   0 sec interval
2016-02-24 15:19:51.488982 ptpd2[17979].eth1 (debug2)    (slv) timerStart:     Set timer ANNOUNCE_RECEIPT to 6.000000
2016-02-24 15:19:51.502632 ptpd2[17979].eth1 (debug2)    (slv) timerIsRunning:   Timer STATISTICS_UPDATE is running
2016-02-24 15:19:51.515680 ptpd2[17979].eth1 (debug2)    (slv) timerIsExpired:   Timer ANNOUNCE_RECEIPT is not expired
2016-02-24 15:19:51.530210 ptpd2[17979].eth1 (debug2)    (slv) timerIsExpired:   Timer OPERATOR_MESSAGES is not expired
2016-02-24 15:19:51.542984 ptpd2[17979].eth1 (debug2)    (slv) timerIsExpired:   Timer DELAYREQ_INTERVAL is expired
2016-02-24 15:19:51.557394 ptpd2[17979].eth1 (debug2)    (slv) event DELAYREQ_INTERVAL_TIMEOUT_EXPIRES
2016-02-24 15:19:51.566805 ptpd2[17979].eth1 (debug1)    (slv) ==> Issue DelayReq (203)
2016-02-24 15:19:51.584607 ptpd2[17979].eth1 (debug3)    (slv) DelayReq MSG sent !
2016-02-24 15:19:51.599843 ptpd2[17979].eth1 (debug3)    (slv) Timer DELAYREQ_INTERVAL start requested at 0.125997 sec interval

Wireshark shows that Delay_Req are sent but there is now answer.
I don't know why.
Any suggestions?

Mirek

[Wojciech Owczarek]

Check your GM and switch configuration, they are probably using P2P, not E2E mode.

Try ptpengine:delay_mechanism=P2P

Thanks,
Wojciech

[Mirek Sobczak]

Hi,
Yes, you are right. CMGPS 588 is using P2P only. This can't be changed.

Thanks
Mirek