From: Keller, J. E <jac...@in...> - 2012-05-07 18:35:01
|
This is using layer2 ethernet, and the peer delay mechanism. The following output With a large chunk of similar output snipped out of the middle. ptp4l[9130]: master offset 0 s2 adj -0 path delay 2246 ptp4l[9130]: master offset 0 s2 adj -0 path delay 2246 ptp4l[9130]: master offset 0 s2 adj -0 path delay 2246 ptp4l[9130]: master offset 0 s2 adj -0 path delay 2246 ptp4l[9130]: master offset 0 s2 adj -0 path delay 2246 ptp4l[9130]: master offset 0 s2 adj -0 path delay 2246 ptp4l[9130]: master offset -1 s2 adj -1 path delay 2246 ptp4l[9130]: master offset 0 s2 adj -1 path delay 2246 ptp4l[9130]: master offset 0 s2 adj -1 path delay 2246 ptp4l[9130]: master offset -1 s2 adj -2 path delay 2246 ptp4l[9130]: master offset 0 s2 adj -1 path delay 2246 ptp4l[9130]: master offset 0 s2 adj -1 path delay 2246 ptp4l[9130]: master offset -1 s2 adj -2 path delay 2246 ptp4l[9130]: master offset 0 s2 adj -1 path delay 2246 ptp4l[9130]: master offset 1 s2 adj -0 path delay 2246 ptp4l[9130]: master offset 1 s2 adj +0 path delay 2246 ptp4l[9130]: master offset 0 s2 adj -1 path delay 2246 ptp4l[9130]: master offset 1 s2 adj +0 path delay 2246 ptp4l[9130]: master offset 1 s2 adj +1 path delay 2246 ptp4l[9130]: master offset -1 s2 adj -1 path delay 2246 ptp4l[9130]: master offset 1 s2 adj +1 path delay 2246 ptp4l[9130]: master offset 0 s2 adj +0 path delay 2246 ptp4l[9130]: master offset 0 s2 adj +0 path delay 2246 ptp4l[9130]: master offset 1 s2 adj +1 path delay 2246 ptp4l[9130]: master offset 0 s2 adj +0 path delay 2246 ptp4l[9130]: master offset -1 s2 adj -1 path delay 2246 ptp4l[9130]: master offset -1 s2 adj -1 path delay 2246 ptp4l[9130]: master offset -1 s2 adj -1 path delay 2246 ptp4l[9130]: master offset 0 s2 adj -1 path delay 2246 ptp4l[9130]: master offset 0 s2 adj -1 path delay 2246 ptp4l[9130]: recvmsg tx timestamp failed: Resource temporarily unavailable --- Here, recvmsg doesn't throw a fault? It just keeps going, but clearly Something is wrong. --- ptp4l[9130]: port 1: send peer delay response failed ptp4l[9130]: master offset -1 s2 adj -2 path delay 2246 ptp4l[9130]: master offset -43938482 s2 adj -43938483 path delay 43940728 ptp4l[9130]: master offset -1171142 s2 adj -14352687 path delay 45114645 ptp4l[9130]: master offset -59895424 s2 adj -73428312 path delay 118195998 ptp4l[9130]: master offset 13533141 s2 adj -17968374 path delay 118195998 ptp4l[9130]: master offset -959421 s2 adj -28400994 path delay 150667108 ptp4l[9130]: master offset 19473679 s2 adj -8255720 path delay 158637541 ptp4l[9130]: master offset -13510091 s2 adj -35397387 path delay 199879994 ptp4l[9130]: master offset 21890754 s2 adj -4049569 path delay 199879994 ptp4l[9130]: master offset -17957633 s2 adj -37330730 path delay 243781453 ptp4l[9130]: master offset -44313497 s2 adj -69073884 path delay 307469087 ptp4l[9130]: master offset 41103457 s2 adj +3049021 path delay 291131068 ptp4l[9130]: master offset -20709563 s2 adj -46432962 path delay 349905094 ptp4l[9130]: master offset 25725135 s2 adj -6211132 path delay 349905094 ptp4l[9130]: master offset 101876700 s2 adj +77657973 path delay 279970952 ptp4l[9130]: master offset 36729373 s2 adj +43073656 path delay 267460041 ptp4l[9130]: master offset -12349994 s2 adj +5013101 path delay 273456473 ptp4l[9130]: master offset -38076301 s2 adj -24418204 path delay 294165576 ptp4l[9130]: master offset -13657682 s2 adj -11422476 path delay 294165576 ptp4l[9130]: master offset 4826744 s2 adj +2964646 path delay 287105953 ptp4l[9130]: master offset 75401760 s2 adj +74987685 path delay 213567350 ptp4l[9130]: master offset 11323148 s2 adj +33529601 path delay 202654824 ptp4l[9130]: master offset 8383536 s2 adj +33986933 path delay 172056349 ptp4l[9130]: master offset -63923663 s2 adj -35805205 path delay 210372287 ptp4l[9130]: master offset -36342287 s2 adj -27400928 path delay 218595737 ptp4l[9130]: master offset -25415962 s2 adj -27377289 path delay 235073893 ptp4l[9130]: master offset 54935774 s2 adj +45349659 path delay 182101818 ptp4l[9130]: master offset -1483435 s2 adj +5411182 path delay 193172785 ptp4l[9130]: master offset -21109976 s2 adj -14660390 path delay 207383663 ptp4l[9130]: master offset -6449498 s2 adj -6332904 path delay 207383663 <snip> ptp4l[9130]: master offset -29956183 s2 adj -45254075 path delay 257139999 ptp4l[9130]: master offset -6172599 s2 adj -30457345 path delay 278614170 ptp4l[9130]: master offset 24289681 s2 adj -1846845 path delay 278614170 ptp4l[9130]: master offset 41848792 s2 adj +22999170 path delay 262904929 ptp4l[9130]: master offset -30372859 s2 adj -36667843 path delay 312127569 ptp4l[9130]: master offset -3607673 s2 adj -19014515 path delay 322030202 ptp4l[9130]: master offset -30156558 s2 adj -46645702 path delay 367598211 ptp4l[9130]: master offset 16491069 s2 adj -9045042 path delay 367598211 ptp4l[9130]: master offset 52317772 s2 adj +31728981 path delay 340821191 ptp4l[9130]: master offset 45628905 s2 adj +40735446 path delay 315780591 ptp4l[9130]: master offset -1315375 s2 adj +7479838 path delay 321986499 ptp4l[9130]: master offset 22069277 s2 adj +30469877 path delay 291118076 ptp4l[9130]: master offset -8404691 s2 adj +6616692 path delay 291118076 ptp4l[9130]: master offset 42513447 s2 adj +55013423 path delay 233579702 ptp4l[9130]: master offset -12508577 s2 adj +12745433 path delay 233579702 ptp4l[9130]: master offset -50524014 s2 adj -29022577 path delay 258844549 ptp4l[9130]: master offset -15524387 s2 adj -9180154 path delay 252865750 ptp4l[9130]: master offset 9203193 s2 adj +10890110 path delay 237321729 ptp4l[9130]: master offset -1686792 s2 adj +2761082 path delay 237321729 ptp4l[9130]: master offset -1209646 s2 adj +2732191 path delay 234082385 ptp4l[9130]: recvmsg failed: No message of desired type ptp4l[9130]: port 1: recv message failed ptp4l[9130]: port 1: SLAVE to FAULTY on FAULT_DETECTED ptp4l[9130]: port 1: FAULTY to LISTENING on FAULT_CLEARED ptp4l[9130]: port 1: new foreign master a0369f.fffe.01b740-1 ptp4l[9130]: selected best master clock a0369f.fffe.01b740 ptp4l[9130]: port 1: LISTENING to UNCALIBRATED on RS_SLAVE ptp4l[9130]: master offset -55861189 s2 adj -52282246 path delay 234082385 ptp4l[9130]: port 1: UNCALIBRATED to SLAVE on MASTER_CLOCK_SELECTED ptp4l[9130]: master offset 73319566 s2 adj +60140152 path delay 157180112 ptp4l[9130]: master offset 25716180 s2 adj +34532636 path delay 144653042 ptp4l[9130]: master offset -5234805 s2 adj +11296505 path delay 141059857 ptp4l[9130]: master offset 67798032 s2 adj +82758901 path delay 56721755 ptp4l[9130]: master offset -14960156 s2 adj +20340122 path delay 56721755 ptp4l[9130]: master offset -34374705 s2 adj -3562474 path delay 55781427 ptp4l[9130]: master offset -18140580 s2 adj +2359240 path delay 43106767 ptp4l[9130]: master offset -4683127 s2 adj +10374519 path delay 27290731 ptp4l[9130]: master offset 12231747 s2 adj +25884455 path delay 1604 ptp4l[9130]: master offset -13652187 s2 adj +3670045 path delay 1115 ptp4l[9130]: master offset -17326157 s2 adj -4099581 path delay 1460 ptp4l[9130]: master offset -13227144 s2 adj -5198415 path delay 1460 ptp4l[9130]: master offset -8028704 s2 adj -3968118 path delay 1808 ptp4l[9130]: master offset -4060291 s2 adj -2408317 path delay 1808 ptp4l[9130]: master offset -1651755 s2 adj -1217868 path delay 2030 ptp4l[9130]: master offset -433545 s2 adj -495184 path delay 2030 ptp4l[9130]: master offset 61834 s2 adj -129869 path delay 2011 ptp4l[9130]: master offset 191631 s2 adj +18478 path delay 2155 ptp4l[9130]: master offset 172983 s2 adj +57320 path delay 2345 ptp4l[9130]: master offset 115659 s2 adj +51890 path delay 2345 ptp4l[9130]: master offset 63794 s2 adj +34723 path delay 2314 ptp4l[9130]: master offset 29063 s2 adj +19130 path delay 2314 ptp4l[9130]: master offset 9993 s2 adj +8779 path delay 2250 ptp4l[9130]: master offset 1210 s2 adj +2994 path delay 2250 ptp4l[9130]: master offset -1782 s2 adj +365 path delay 2247 ptp4l[9130]: master offset -2148 s2 adj -535 path delay 2246 ptp4l[9130]: master offset -1612 s2 adj -644 path delay 2246 ptp4l[9130]: master offset -971 s2 adj -486 path delay 2246 ptp4l[9130]: master offset -483 s2 adj -290 path delay 2245 Much later on a different error throws a fault and suddenly everything is better. Is that behavior in the Middle possibly caused by some faulty state that wasn't cleared? I am not sure, but those path delays And values seem incredibly wrong. I think it's because somehow one of the sequence numbers got Out of order and messed up. I am going to try and take a look at that code and see if I can find the issue, But I am wondering if you've seen behavior like this. - Jake |