skiboot
skiboot copied to clipboard
Published
20 hours ago •
open-power
open-power
PCIe to PCI bridge will not train
We've run across an interesting device that's failing to train on POWER9. It's a PCIe to PCI bridge built around the PI7C9X130 chip, it looks solidly constructed and appears to be powered up correctly, but skiboot fails to train the link:
[ 58.229791464,5] PCI: Resetting PHBs and training links...
[ 60.243078664,3] PHB#0001[0:1]: LINK: Timeout waiting for link up
[ 62.447106167,3] PHB#0001[0:1]: LINK: Timeout waiting for link up
[ 65.139140552,3] PHB#0001[0:1]: LINK: Timeout waiting for link up
[ 67.343171186,3] PHB#0001[0:1]: LINK: Timeout waiting for link up
[ 67.343232661,3] PHB#0001[0:1]: Electrical link detected but won't train
[ 67.343692522,5] PCI: Probing slots...
What additional information do you need to debug?
Can you try a trace with this?
nvram -p ibm,skiboot --update-config pci-tracing=true
Reboot and then send:
grep PHB#0001 /sys/firmware/opal/msglog
@mikey If I'm reading this right, it looks like it's trying to train at x16 when the card is much narrower; it only has a x1 edge connector.
[ 63.130314433,7] PHB#0001:00:00.0 PCI: Registered PHB
[ 63.130316302,6] PHB#0001[0:1]: Found /pciex@600c3c0100000 @0x600c3c0100000
[ 63.130317860,6] PHB#0001[0:1]: M32 [0x000600c080000000..0x000600c0ffffffff]
[ 63.130319118,6] PHB#0001[0:1]: M64 [0x0006004000000000..0x0006007fffffffff]
[ 63.130320763,7] PHB#0001[0:1]: Base location code not found !
[ 63.130322481,7] PHB#0001[0:1]: Core revision 0xa40002
[ 63.130323545,7] PHB#0001[0:1]: Found 256 max PEs and 2048 IRQs
[ 63.130324697,6] PHB#0001[0:1]: Max link speed: GEN4
[ 63.130325625,7] PHB#0001[0:1]: Override lane equalization settings:
[ 63.130326584,7] PHB#0001[0:1]: 0x7777777777777777 0x7777777777777777
[ 63.130327721,7] PHB#0001[0:1]: 0x7777777777777777 0x7777777777777777
[ 63.130328926,7] PHB#0001[0:1]: 0x7777777777777777 0x7777777777777777
[ 63.130434438,7] PHB#0001[0:1]: Initializing PHB4...
[ 63.130435538,7] PHB#0001[0:1]: Default system config: 0x4210000040200000
[ 63.130436705,7] PHB#0001[0:1]: New system config : 0x4210000040200000
[ 63.130438059,7] PHB#0001[0:1]: Initial PHB CRESET is 0xe000000000000000
[ 63.130439524,7] PHB#0001[0:1]: Waiting for DLP PG reset to complete...
[ 63.130959693,7] PHB#0001[0:1]: Reset state SRC_ID: 0ff8000000000000
[ 63.131163400,7] PHB#0001[0:1]: Initialization complete
[ 63.149275339,7] PHB#0001[0:1]: FRESET: Assert skipped
[ 63.149278629,7] PHB#0001[0:1]: FRESET: Deassert
[ 63.149284181,3] PHB#0001[0:1]: TRACE:0x0000001101000000 0ms GEN1:x16:detect
[ 63.155925383,3] PHB#0001[0:1]: TRACE:0x0000102101000000 12ms presence GEN1:x16:polling
[ 63.156515174,3] PHB#0001[0:1]: TRACE:0x0000183101000000 14ms training GEN1:x16:config
[ 63.156589677,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 14ms training GEN1:x01:recovery
[ 63.168857402,3] PHB#0001[0:1]: TRACE:0x0000001011000000 38ms GEN1:x01:detect
[ 63.168990387,3] PHB#0001[0:1]: TRACE:0x0000001101000000 38ms GEN1:x16:detect
[ 63.175284963,3] PHB#0001[0:1]: TRACE:0x0000102101000000 50ms presence GEN1:x16:polling
[ 63.175366862,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 50ms training GEN1:x01:recovery
[ 63.187661800,3] PHB#0001[0:1]: TRACE:0x0000001011000000 74ms GEN1:x01:detect
[ 63.187795136,3] PHB#0001[0:1]: TRACE:0x0000001101000000 75ms GEN1:x16:detect
[ 63.194089772,3] PHB#0001[0:1]: TRACE:0x0000102101000000 87ms presence GEN1:x16:polling
[ 63.194171702,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 87ms training GEN1:x01:recovery
[ 63.206466950,3] PHB#0001[0:1]: TRACE:0x0000001011000000 111ms GEN1:x01:detect
[ 63.206600132,3] PHB#0001[0:1]: TRACE:0x0000001101000000 111ms GEN1:x16:detect
[ 63.212894763,3] PHB#0001[0:1]: TRACE:0x0000102101000000 124ms presence GEN1:x16:polling
[ 63.212977331,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 124ms training GEN1:x01:recovery
[ 63.225271600,3] PHB#0001[0:1]: TRACE:0x0000001011000000 148ms GEN1:x01:detect
[ 63.225405238,3] PHB#0001[0:1]: TRACE:0x0000001101000000 148ms GEN1:x16:detect
[ 63.231700017,3] PHB#0001[0:1]: TRACE:0x0000102101000000 160ms presence GEN1:x16:polling
[ 63.231781853,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 161ms training GEN1:x01:recovery
[ 63.244076818,3] PHB#0001[0:1]: TRACE:0x0000001011000000 185ms GEN1:x01:detect
[ 63.244210227,3] PHB#0001[0:1]: TRACE:0x0000001101000000 185ms GEN1:x16:detect
[ 63.250504816,3] PHB#0001[0:1]: TRACE:0x0000102101000000 197ms presence GEN1:x16:polling
[ 63.250577429,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 197ms training GEN1:x01:recovery
[ 63.262881402,3] PHB#0001[0:1]: TRACE:0x0000001011000000 221ms GEN1:x01:detect
[ 63.263015191,3] PHB#0001[0:1]: TRACE:0x0000001101000000 222ms GEN1:x16:detect
[ 63.269309192,3] PHB#0001[0:1]: TRACE:0x0000102101000000 234ms presence GEN1:x16:polling
[ 63.269385319,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 234ms training GEN1:x01:recovery
[ 63.281686597,3] PHB#0001[0:1]: TRACE:0x0000001011000000 258ms GEN1:x01:detect
[ 63.281819687,3] PHB#0001[0:1]: TRACE:0x0000001101000000 258ms GEN1:x16:detect
[ 63.288114344,3] PHB#0001[0:1]: TRACE:0x0000102101000000 271ms presence GEN1:x16:polling
[ 63.288202783,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 271ms training GEN1:x01:recovery
[ 63.300491094,3] PHB#0001[0:1]: TRACE:0x0000001011000000 295ms GEN1:x01:detect
[ 63.300624778,3] PHB#0001[0:1]: TRACE:0x0000001101000000 295ms GEN1:x16:detect
[ 63.306919225,3] PHB#0001[0:1]: TRACE:0x0000102101000000 307ms presence GEN1:x16:polling
[ 63.307010490,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 308ms training GEN1:x01:recovery
[ 63.319296090,3] PHB#0001[0:1]: TRACE:0x0000001011000000 332ms GEN1:x01:detect
[ 63.319429203,3] PHB#0001[0:1]: TRACE:0x0000001101000000 332ms GEN1:x16:detect
[ 63.325723980,3] PHB#0001[0:1]: TRACE:0x0000102101000000 344ms presence GEN1:x16:polling
[ 63.325812501,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 344ms training GEN1:x01:recovery
[ 63.338100788,3] PHB#0001[0:1]: TRACE:0x0000001011000000 368ms GEN1:x01:detect
[ 63.338234387,3] PHB#0001[0:1]: TRACE:0x0000001101000000 369ms GEN1:x16:detect
[ 63.344528836,3] PHB#0001[0:1]: TRACE:0x0000102101000000 381ms presence GEN1:x16:polling
[ 63.344619697,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 381ms training GEN1:x01:recovery
[ 63.356905803,3] PHB#0001[0:1]: TRACE:0x0000001011000000 405ms GEN1:x01:detect
[ 63.357039049,3] PHB#0001[0:1]: TRACE:0x0000001101000000 405ms GEN1:x16:detect
[ 63.363333525,3] PHB#0001[0:1]: TRACE:0x0000102101000000 418ms presence GEN1:x16:polling
[ 63.363419125,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 418ms training GEN1:x01:recovery
[ 63.375710368,3] PHB#0001[0:1]: TRACE:0x0000001011000000 442ms GEN1:x01:detect
[ 63.375844179,3] PHB#0001[0:1]: TRACE:0x0000001101000000 442ms GEN1:x16:detect
[ 63.382138248,3] PHB#0001[0:1]: TRACE:0x0000102101000000 454ms presence GEN1:x16:polling
[ 63.382205522,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 454ms training GEN1:x01:recovery
[ 63.394515151,3] PHB#0001[0:1]: TRACE:0x0000045811000000 478ms GEN1:x01:recovery
[ 63.394593051,3] PHB#0001[0:1]: TRACE:0x0000001011000000 479ms GEN1:x01:detect
[ 63.394659286,3] PHB#0001[0:1]: TRACE:0x0000001101000000 479ms GEN1:x16:detect
[ 63.400943267,3] PHB#0001[0:1]: TRACE:0x0000102101000000 491ms presence GEN1:x16:polling
[ 63.401017355,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 491ms training GEN1:x01:recovery
[ 63.413319994,3] PHB#0001[0:1]: TRACE:0x0000001011000000 515ms GEN1:x01:detect
[ 63.413453828,3] PHB#0001[0:1]: TRACE:0x0000001101000000 515ms GEN1:x16:detect
[ 63.419747977,3] PHB#0001[0:1]: TRACE:0x0000102101000000 528ms presence GEN1:x16:polling
[ 63.419829933,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 528ms training GEN1:x01:recovery
[ 63.432124925,3] PHB#0001[0:1]: TRACE:0x0000045811000000 552ms GEN1:x01:recovery
[ 63.432199997,3] PHB#0001[0:1]: TRACE:0x0000001011000000 552ms GEN1:x01:detect
[ 63.432262011,3] PHB#0001[0:1]: TRACE:0x0000001101000000 552ms GEN1:x16:detect
[ 63.438553106,3] PHB#0001[0:1]: TRACE:0x0000102101000000 564ms presence GEN1:x16:polling
[ 63.438625098,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 565ms training GEN1:x01:recovery
[ 63.450929868,3] PHB#0001[0:1]: TRACE:0x0000001011000000 589ms GEN1:x01:detect
[ 63.451063592,3] PHB#0001[0:1]: TRACE:0x0000001101000000 589ms GEN1:x16:detect
[ 63.457357702,3] PHB#0001[0:1]: TRACE:0x0000102101000000 601ms presence GEN1:x16:polling
[ 63.457442490,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 601ms training GEN1:x01:recovery
[ 63.469734523,3] PHB#0001[0:1]: TRACE:0x0000001011000000 625ms GEN1:x01:detect
[ 63.469868281,3] PHB#0001[0:1]: TRACE:0x0000001101000000 626ms GEN1:x16:detect
[ 63.476162381,3] PHB#0001[0:1]: TRACE:0x0000102101000000 638ms presence GEN1:x16:polling
[ 63.476234559,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 638ms training GEN1:x01:recovery
[ 63.488539876,3] PHB#0001[0:1]: TRACE:0x0000045811000000 662ms GEN1:x01:recovery
[ 63.488627434,3] PHB#0001[0:1]: TRACE:0x0000001011000000 662ms GEN1:x01:detect
[ 63.488687916,3] PHB#0001[0:1]: TRACE:0x0000001101000000 662ms GEN1:x16:detect
[ 63.494967309,3] PHB#0001[0:1]: TRACE:0x0000102101000000 675ms presence GEN1:x16:polling
[ 63.495054279,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 675ms training GEN1:x01:recovery
[ 63.507344461,3] PHB#0001[0:1]: TRACE:0x0000001011000000 699ms GEN1:x01:detect
[ 63.507477587,3] PHB#0001[0:1]: TRACE:0x0000001101000000 699ms GEN1:x16:detect
[ 64.001772218,3] PHB#0001[0:1]: TRACE:0x0000102101000000 711ms presence GEN1:x16:polling
[ 64.001855371,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 712ms training GEN1:x01:recovery
[ 64.014149116,3] PHB#0001[0:1]: TRACE:0x0000001011000000 736ms GEN1:x01:detect
[ 64.014282471,3] PHB#0001[0:1]: TRACE:0x0000001101000000 736ms GEN1:x16:detect
[ 64.020577298,3] PHB#0001[0:1]: TRACE:0x0000102101000000 748ms presence GEN1:x16:polling
[ 64.020658029,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 748ms training GEN1:x01:recovery
[ 64.032953927,3] PHB#0001[0:1]: TRACE:0x0000001011000000 772ms GEN1:x01:detect
[ 64.033087464,3] PHB#0001[0:1]: TRACE:0x0000001101000000 773ms GEN1:x16:detect
[ 64.039382000,3] PHB#0001[0:1]: TRACE:0x0000102101000000 785ms presence GEN1:x16:polling
[ 64.039469829,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 785ms training GEN1:x01:recovery
[ 64.051759080,3] PHB#0001[0:1]: TRACE:0x0000001011000000 809ms GEN1:x01:detect
[ 64.051892213,3] PHB#0001[0:1]: TRACE:0x0000001101000000 809ms GEN1:x16:detect
[ 64.058186780,3] PHB#0001[0:1]: TRACE:0x0000102101000000 822ms presence GEN1:x16:polling
[ 64.058261133,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 822ms training GEN1:x01:recovery
[ 64.070563906,3] PHB#0001[0:1]: TRACE:0x0000001811000000 846ms GEN1:x01:detect
[ 64.070647548,3] PHB#0001[0:1]: TRACE:0x0000001011000000 846ms GEN1:x01:detect
[ 64.070715969,3] PHB#0001[0:1]: TRACE:0x0000001101000000 846ms GEN1:x16:detect
[ 64.076991621,3] PHB#0001[0:1]: TRACE:0x0000102101000000 858ms presence GEN1:x16:polling
[ 64.077069601,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 858ms training GEN1:x01:recovery
[ 64.089368626,3] PHB#0001[0:1]: TRACE:0x0000001011000000 882ms GEN1:x01:detect
[ 64.089501893,3] PHB#0001[0:1]: TRACE:0x0000001101000000 883ms GEN1:x16:detect
[ 64.095796872,3] PHB#0001[0:1]: TRACE:0x0000102101000000 895ms presence GEN1:x16:polling
[ 64.095877274,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 895ms training GEN1:x01:recovery
[ 64.108173726,3] PHB#0001[0:1]: TRACE:0x0000001011000000 919ms GEN1:x01:detect
[ 64.108306857,3] PHB#0001[0:1]: TRACE:0x0000001101000000 919ms GEN1:x16:detect
[ 64.114601820,3] PHB#0001[0:1]: TRACE:0x0000102101000000 932ms presence GEN1:x16:polling
[ 64.114695874,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 932ms training GEN1:x01:recovery
[ 64.126978505,3] PHB#0001[0:1]: TRACE:0x0000001011000000 956ms GEN1:x01:detect
[ 64.127111752,3] PHB#0001[0:1]: TRACE:0x0000001101000000 956ms GEN1:x16:detect
[ 64.133406614,3] PHB#0001[0:1]: TRACE:0x0000102101000000 968ms presence GEN1:x16:polling
[ 64.310670042,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1315ms training GEN1:x01:recovery
[ 64.315026713,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1323ms GEN1:x01:detect
[ 64.315160041,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1323ms GEN1:x16:detect
[ 64.321454571,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1336ms presence GEN1:x16:polling
[ 64.321534633,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1336ms training GEN1:x01:recovery
[ 64.333831477,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1360ms GEN1:x01:detect
[ 64.333964917,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1360ms GEN1:x16:detect
[ 64.340259582,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1372ms presence GEN1:x16:polling
[ 64.340352440,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1373ms training GEN1:x01:recovery
[ 64.352636452,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1397ms GEN1:x01:detect
[ 64.352769768,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1397ms GEN1:x16:detect
[ 64.359064291,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1409ms presence GEN1:x16:polling
[ 64.359147693,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1409ms training GEN1:x01:recovery
[ 64.371441237,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1433ms GEN1:x01:detect
[ 64.371574586,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1434ms GEN1:x16:detect
[ 64.377869166,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1446ms presence GEN1:x16:polling
[ 64.377939557,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1446ms training GEN1:x01:recovery
[ 64.390246133,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1470ms GEN1:x01:detect
[ 64.390379459,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1470ms GEN1:x16:detect
[ 64.396673994,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1483ms presence GEN1:x16:polling
[ 64.396758380,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1483ms training GEN1:x01:recovery
[ 64.409050950,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1507ms GEN1:x01:detect
[ 64.409184321,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1507ms GEN1:x16:detect
[ 64.415478765,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1519ms presence GEN1:x16:polling
[ 64.415561333,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1520ms training GEN1:x01:recovery
[ 64.427855742,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1544ms GEN1:x01:detect
[ 64.427989130,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1544ms GEN1:x16:detect
[ 64.434283628,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1556ms presence GEN1:x16:polling
[ 64.434363458,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1556ms training GEN1:x01:recovery
[ 64.446660567,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1580ms GEN1:x01:detect
[ 64.446793934,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1581ms GEN1:x16:detect
[ 64.453088551,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1593ms presence GEN1:x16:polling
[ 64.453169530,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1593ms training GEN1:x01:recovery
[ 64.465465393,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1617ms GEN1:x01:detect
[ 64.465598822,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1617ms GEN1:x16:detect
[ 64.471893349,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1630ms presence GEN1:x16:polling
[ 64.471971746,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1630ms training GEN1:x01:recovery
[ 64.484270257,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1654ms GEN1:x01:detect
[ 64.484403673,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1654ms GEN1:x16:detect
[ 64.490698126,3] PHB#0001[0:1]: TRACE:0x0000101101000000 1666ms presence GEN1:x16:detect
[ 64.490775271,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1666ms training GEN1:x01:recovery
[ 64.503075084,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1690ms GEN1:x01:detect
[ 64.503208549,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1691ms GEN1:x16:detect
[ 64.509503061,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1703ms presence GEN1:x16:polling
[ 64.509571986,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1703ms training GEN1:x01:recovery
[ 65.009879971,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1727ms GEN1:x01:detect
[ 65.210160401,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2118ms training GEN1:x01:recovery
[ 65.216733184,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2131ms GEN1:x01:detect
[ 65.216866705,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2131ms GEN1:x16:detect
[ 65.223161137,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2144ms presence GEN1:x16:polling
[ 65.223251257,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2144ms training GEN1:x01:recovery
[ 65.235538082,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2168ms GEN1:x01:detect
[ 65.235671457,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2168ms GEN1:x16:detect
[ 65.241966119,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2181ms presence GEN1:x16:polling
[ 65.242034969,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2181ms training GEN1:x01:recovery
[ 65.254342936,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2205ms GEN1:x01:detect
[ 65.254476305,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2205ms GEN1:x16:detect
[ 65.260770828,3] PHB#0001[0:1]: TRACE:0x0000101101000000 2217ms presence GEN1:x16:detect
[ 65.260841767,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2217ms training GEN1:x01:recovery
[ 65.273147715,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2241ms GEN1:x01:detect
[ 65.273281099,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2242ms GEN1:x16:detect
[ 65.279575778,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2254ms presence GEN1:x16:polling
[ 65.279667928,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2254ms training GEN1:x01:recovery
[ 65.291952615,3] PHB#0001[0:1]: TRACE:0x0000045811000000 2278ms GEN1:x01:recovery
[ 65.292023259,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2278ms GEN1:x01:detect
[ 65.292086020,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2278ms GEN1:x16:detect
[ 65.298380601,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2291ms presence GEN1:x16:polling
[ 65.298455793,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2291ms training GEN1:x01:recovery
[ 65.310757451,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2315ms GEN1:x01:detect
[ 65.310890952,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2315ms GEN1:x16:detect
[ 65.317185358,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2327ms presence GEN1:x16:polling
[ 65.317272361,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2328ms training GEN1:x01:recovery
[ 65.329562331,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2352ms GEN1:x01:detect
[ 65.329695660,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2352ms GEN1:x16:detect
[ 65.335990277,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2364ms presence GEN1:x16:polling
[ 65.336084647,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2364ms training GEN1:x01:recovery
[ 65.348367148,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2388ms GEN1:x01:detect
[ 65.348500605,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2389ms GEN1:x16:detect
[ 65.354795171,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2401ms presence GEN1:x16:polling
[ 65.354886954,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2401ms training GEN1:x01:recovery
[ 65.367171993,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2425ms GEN1:x01:detect
[ 65.367305409,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2425ms GEN1:x16:detect
[ 65.373599960,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2438ms presence GEN1:x16:polling
[ 65.373680522,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2438ms training GEN1:x01:recovery
[ 65.385976769,3] PHB#0001[0:1]: TRACE:0x0000005811000000 2462ms GEN1:x01:recovery
[ 65.386051942,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2462ms GEN1:x01:detect
[ 65.386115660,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2462ms GEN1:x16:detect
[ 65.392404743,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2474ms presence GEN1:x16:polling
[ 65.392486343,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2475ms training GEN1:x01:recovery
[ 65.404781707,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2499ms GEN1:x01:detect
[ 65.404915073,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2499ms GEN1:x16:detect
[ 65.411209697,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2511ms presence GEN1:x16:polling
[ 65.411283416,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2511ms training GEN1:x01:recovery
[ 65.423586552,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2535ms GEN1:x01:detect
[ 66.110652117,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2924ms training GEN1:x01:recovery
[ 66.118439789,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2939ms GEN1:x01:detect
[ 66.118573171,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2940ms GEN1:x16:detect
[ 66.124867860,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2952ms presence GEN1:x16:polling
[ 66.124939976,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2952ms training GEN1:x01:recovery
[ 66.137244666,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2976ms GEN1:x01:detect
[ 66.137377982,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2976ms GEN1:x16:detect
[ 66.143672707,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2989ms presence GEN1:x16:polling
[ 66.143753736,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2989ms training GEN1:x01:recovery
[ 66.149279939,3] PHB#0001[0:1]: TRACE: Timeout waiting for link up.
[ 66.149800285,7] PHB#0001[0:1]: LINK: Start polling
[ 66.150320178,7] PHB#0001[0:1]: LINK: Electrical link detected
[ 68.152234704,3] PHB#0001[0:1]: LINK: Timeout waiting for link up
[ 68.152298590,7] PHB#0001[0:1]: LINK: DLP train control: 0x0000001101000000
[ 68.152819196,7] PHB#0001[0:1]: CRESET: Starts
[ 68.157982264,7] PHB#0001[0:1]: CRESET: No pending transactions
[ 68.163112626,7] PHB#0001[0:1]: CRESET: Reinitialization
[ 68.163113533,7] PHB#0001[0:1]: Initializing PHB4...
[ 68.163114554,7] PHB#0001[0:1]: Default system config: 0x4210000040200000
[ 68.163115988,7] PHB#0001[0:1]: New system config : 0x4210000040200000
[ 68.163117350,7] PHB#0001[0:1]: Initial PHB CRESET is 0xe000000000000000
[ 68.163118761,7] PHB#0001[0:1]: Waiting for DLP PG reset to complete...
[ 68.163639020,7] PHB#0001[0:1]: Reset state SRC_ID: 0ff8000000000000
[ 68.163843935,7] PHB#0001[0:1]: Initialization complete
[ 68.215051784,7] PHB#0001[0:1]: FRESET: Starts
[ 68.215052694,7] PHB#0001[0:1]: FRESET: Prepare for link down
[ 68.215053894,7] PHB#0001[0:1]: FRESET: Assert
[ 68.343061988,7] PHB#0001[0:1]: FRESET: Deassert
[ 68.343067194,3] PHB#0001[0:1]: TRACE:0x0000001101000000 0ms GEN1:x16:detect
[ 68.352266765,3] PHB#0001[0:1]: TRACE:0x0000102101000000 17ms presence GEN1:x16:polling
[ 68.352524465,3] PHB#0001[0:1]: TRACE:0x0000183101000000 18ms training GEN1:x16:config
[ 68.352625505,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 18ms training GEN1:x01:recovery
[ 68.364866694,3] PHB#0001[0:1]: TRACE:0x0000005811000000 42ms GEN1:x01:recovery
[ 68.365003276,3] PHB#0001[0:1]: TRACE:0x0000001101000000 42ms GEN1:x16:detect
[ 68.371294698,3] PHB#0001[0:1]: TRACE:0x0000102101000000 55ms presence GEN1:x16:polling
[ 68.371393466,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 55ms training GEN1:x01:recovery
[ 68.383671835,3] PHB#0001[0:1]: TRACE:0x0000001011000000 79ms GEN1:x01:detect
[ 68.383804979,3] PHB#0001[0:1]: TRACE:0x0000001101000000 79ms GEN1:x16:detect
[ 68.390099425,3] PHB#0001[0:1]: TRACE:0x0000102101000000 91ms presence GEN1:x16:polling
[ 68.390217812,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 92ms training GEN1:x01:recovery
[ 68.402476771,3] PHB#0001[0:1]: TRACE:0x0000001011000000 116ms GEN1:x01:detect
[ 68.402609937,3] PHB#0001[0:1]: TRACE:0x0000001101000000 116ms GEN1:x16:detect
[ 68.408904678,3] PHB#0001[0:1]: TRACE:0x0000102101000000 128ms presence GEN1:x16:polling
[ 68.409023901,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 128ms training GEN1:x01:recovery
[ 68.421281974,3] PHB#0001[0:1]: TRACE:0x0000001011000000 152ms GEN1:x01:detect
[ 68.421423561,3] PHB#0001[0:1]: TRACE:0x0000001101000000 153ms GEN1:x16:detect
[ 68.427709445,3] PHB#0001[0:1]: TRACE:0x0000102101000000 165ms presence GEN1:x16:polling
[ 68.427811757,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 165ms training GEN1:x01:recovery
[ 68.440086398,3] PHB#0001[0:1]: TRACE:0x0000001011000000 189ms GEN1:x01:detect
[ 68.440219986,3] PHB#0001[0:1]: TRACE:0x0000001101000000 189ms GEN1:x16:detect
[ 68.446514453,3] PHB#0001[0:1]: TRACE:0x0000102101000000 202ms presence GEN1:x16:polling
[ 68.446616489,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 202ms training GEN1:x01:recovery
[ 68.458891388,3] PHB#0001[0:1]: TRACE:0x0000001011000000 226ms GEN1:x01:detect
[ 68.459024626,3] PHB#0001[0:1]: TRACE:0x0000001101000000 226ms GEN1:x16:detect
[ 68.465319216,3] PHB#0001[0:1]: TRACE:0x0000102101000000 238ms presence GEN1:x16:polling
[ 68.465468052,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 239ms training GEN1:x01:recovery
[ 68.477696230,3] PHB#0001[0:1]: TRACE:0x0000045811000000 262ms GEN1:x01:recovery
[ 68.477844493,3] PHB#0001[0:1]: TRACE:0x0000001101000000 263ms GEN1:x16:detect
[ 68.484123806,3] PHB#0001[0:1]: TRACE:0x0000102101000000 275ms presence GEN1:x16:polling
[ 68.484239786,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 275ms training GEN1:x01:recovery
[ 68.496501015,3] PHB#0001[0:1]: TRACE:0x0000001011000000 299ms GEN1:x01:detect
[ 68.496634653,3] PHB#0001[0:1]: TRACE:0x0000001101000000 299ms GEN1:x16:detect
[ 68.502928692,3] PHB#0001[0:1]: TRACE:0x0000102101000000 312ms presence GEN1:x16:polling
[ 68.503001938,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 312ms training GEN1:x01:recovery
[ 69.003305733,3] PHB#0001[0:1]: TRACE:0x0000001011000000 336ms GEN1:x01:detect
[ 69.003439014,3] PHB#0001[0:1]: TRACE:0x0000001101000000 336ms GEN1:x16:detect
[ 69.009733689,3] PHB#0001[0:1]: TRACE:0x0000102101000000 348ms presence GEN1:x16:polling
[ 69.009807972,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 349ms training GEN1:x01:recovery
[ 69.022110662,3] PHB#0001[0:1]: TRACE:0x0000001011000000 373ms GEN1:x01:detect
[ 69.022244223,3] PHB#0001[0:1]: TRACE:0x0000001101000000 373ms GEN1:x16:detect
[ 69.028538561,3] PHB#0001[0:1]: TRACE:0x0000102101000000 385ms presence GEN1:x16:polling
[ 69.028617918,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 385ms training GEN1:x01:recovery
[ 69.040915489,3] PHB#0001[0:1]: TRACE:0x0000001011000000 409ms GEN1:x01:detect
[ 69.041048790,3] PHB#0001[0:1]: TRACE:0x0000001101000000 410ms GEN1:x16:detect
[ 69.047343586,3] PHB#0001[0:1]: TRACE:0x0000102101000000 422ms presence GEN1:x16:polling
[ 69.047421065,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 422ms training GEN1:x01:recovery
[ 69.059720167,3] PHB#0001[0:1]: TRACE:0x0000001011000000 446ms GEN1:x01:detect
[ 69.059853910,3] PHB#0001[0:1]: TRACE:0x0000001101000000 446ms GEN1:x16:detect
[ 69.066148157,3] PHB#0001[0:1]: TRACE:0x0000102101000000 459ms presence GEN1:x16:polling
[ 69.066252934,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 459ms training GEN1:x01:recovery
[ 69.078525173,3] PHB#0001[0:1]: TRACE:0x0000001011000000 483ms GEN1:x01:detect
[ 69.078658677,3] PHB#0001[0:1]: TRACE:0x0000001101000000 483ms GEN1:x16:detect
[ 69.084952862,3] PHB#0001[0:1]: TRACE:0x0000102101000000 495ms presence GEN1:x16:polling
[ 69.085021594,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 496ms training GEN1:x01:recovery
[ 69.097330069,3] PHB#0001[0:1]: TRACE:0x0000001011000000 520ms GEN1:x01:detect
[ 69.097463287,3] PHB#0001[0:1]: TRACE:0x0000001101000000 520ms GEN1:x16:detect
[ 69.103758227,3] PHB#0001[0:1]: TRACE:0x0000102101000000 532ms presence GEN1:x16:polling
[ 69.103839506,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 532ms training GEN1:x01:recovery
[ 69.116135038,3] PHB#0001[0:1]: TRACE:0x0000001011000000 556ms GEN1:x01:detect
[ 69.116268177,3] PHB#0001[0:1]: TRACE:0x0000001101000000 557ms GEN1:x16:detect
[ 69.122562763,3] PHB#0001[0:1]: TRACE:0x0000102101000000 569ms presence GEN1:x16:polling
[ 69.122651013,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 569ms training GEN1:x01:recovery
[ 69.134939508,3] PHB#0001[0:1]: TRACE:0x0000001011000000 593ms GEN1:x01:detect
[ 69.135072884,3] PHB#0001[0:1]: TRACE:0x0000001101000000 593ms GEN1:x16:detect
[ 69.141367515,3] PHB#0001[0:1]: TRACE:0x0000102101000000 606ms presence GEN1:x16:polling
[ 69.141449964,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 606ms training GEN1:x01:recovery
[ 69.153744452,3] PHB#0001[0:1]: TRACE:0x0000001011000000 630ms GEN1:x01:detect
[ 69.153878043,3] PHB#0001[0:1]: TRACE:0x0000001101000000 630ms GEN1:x16:detect
[ 69.160172291,3] PHB#0001[0:1]: TRACE:0x0000102101000000 642ms presence GEN1:x16:polling
[ 69.160254457,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 642ms training GEN1:x01:recovery
[ 69.172549575,3] PHB#0001[0:1]: TRACE:0x0000001011000000 666ms GEN1:x01:detect
[ 69.172682772,3] PHB#0001[0:1]: TRACE:0x0000001101000000 667ms GEN1:x16:detect
[ 69.178977293,3] PHB#0001[0:1]: TRACE:0x0000102101000000 679ms presence GEN1:x16:polling
[ 69.179059212,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 679ms training GEN1:x01:recovery
[ 69.191354153,3] PHB#0001[0:1]: TRACE:0x0000045811000000 703ms GEN1:x01:recovery
[ 69.191432196,3] PHB#0001[0:1]: TRACE:0x0000001011000000 703ms GEN1:x01:detect
[ 69.191492932,3] PHB#0001[0:1]: TRACE:0x0000001101000000 703ms GEN1:x16:detect
[ 69.197782370,3] PHB#0001[0:1]: TRACE:0x0000102101000000 716ms presence GEN1:x16:polling
[ 69.197879863,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 716ms training GEN1:x01:recovery
[ 69.210159268,3] PHB#0001[0:1]: TRACE:0x0000001011000000 740ms GEN1:x01:detect
[ 69.210292722,3] PHB#0001[0:1]: TRACE:0x0000001101000000 740ms GEN1:x16:detect
[ 69.216586852,3] PHB#0001[0:1]: TRACE:0x0000102101000000 752ms presence GEN1:x16:polling
[ 69.216689242,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 753ms training GEN1:x01:recovery
[ 69.228964153,3] PHB#0001[0:1]: TRACE:0x0000001011000000 777ms GEN1:x01:detect
[ 69.229097371,3] PHB#0001[0:1]: TRACE:0x0000001101000000 777ms GEN1:x16:detect
[ 69.235391948,3] PHB#0001[0:1]: TRACE:0x0000102101000000 789ms presence GEN1:x16:polling
[ 69.235529476,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 789ms training GEN1:x01:recovery
[ 69.247768973,3] PHB#0001[0:1]: TRACE:0x0000001011000000 813ms GEN1:x01:detect
[ 69.247918115,3] PHB#0001[0:1]: TRACE:0x0000001101000000 814ms GEN1:x16:detect
[ 69.254197021,3] PHB#0001[0:1]: TRACE:0x0000102101000000 826ms presence GEN1:x16:polling
[ 69.254294057,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 826ms training GEN1:x01:recovery
[ 69.266573377,3] PHB#0001[0:1]: TRACE:0x0000001011000000 850ms GEN1:x01:detect
[ 69.266707288,3] PHB#0001[0:1]: TRACE:0x0000001101000000 850ms GEN1:x16:detect
[ 69.273001507,3] PHB#0001[0:1]: TRACE:0x0000102101000000 863ms presence GEN1:x16:polling
[ 69.273118010,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 863ms training GEN1:x01:recovery
[ 69.285378654,3] PHB#0001[0:1]: TRACE:0x0000005811000000 887ms GEN1:x01:recovery
[ 69.285493464,3] PHB#0001[0:1]: TRACE:0x0000001011000000 887ms GEN1:x01:detect
[ 69.285585010,3] PHB#0001[0:1]: TRACE:0x0000001101000000 887ms GEN1:x16:detect
[ 69.291806641,3] PHB#0001[0:1]: TRACE:0x0000102101000000 899ms presence GEN1:x16:polling
[ 69.291938861,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 900ms training GEN1:x01:recovery
[ 69.304183281,3] PHB#0001[0:1]: TRACE:0x0000001011000000 924ms GEN1:x01:detect
[ 69.304316830,3] PHB#0001[0:1]: TRACE:0x0000001101000000 924ms GEN1:x16:detect
[ 69.310611097,3] PHB#0001[0:1]: TRACE:0x0000102101000000 936ms presence GEN1:x16:polling
[ 69.310721160,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 936ms training GEN1:x01:recovery
[ 69.322988066,3] PHB#0001[0:1]: TRACE:0x0000001011000000 960ms GEN1:x01:detect
[ 69.323135669,3] PHB#0001[0:1]: TRACE:0x0000001101000000 961ms GEN1:x16:detect
[ 69.329416021,3] PHB#0001[0:1]: TRACE:0x0000102101000000 973ms presence GEN1:x16:polling
[ 69.329517490,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 973ms training GEN1:x01:recovery
[ 69.341793226,3] PHB#0001[0:1]: TRACE:0x0000001011000000 997ms GEN1:x01:detect
[ 69.341926348,3] PHB#0001[0:1]: TRACE:0x0000001101000000 997ms GEN1:x16:detect
[ 69.348220903,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1010ms presence GEN1:x16:polling
[ 69.348339168,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1010ms training GEN1:x01:recovery
[ 69.360597767,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1034ms GEN1:x01:detect
[ 69.360731142,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1034ms GEN1:x16:detect
[ 69.367025703,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1046ms presence GEN1:x16:polling
[ 69.367132995,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1047ms training GEN1:x01:recovery
[ 69.379402630,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1070ms GEN1:x01:detect
[ 69.379536099,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1071ms GEN1:x16:detect
[ 69.385830621,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1083ms presence GEN1:x16:polling
[ 69.385914822,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1083ms training GEN1:x01:recovery
[ 69.398207499,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1107ms GEN1:x01:detect
[ 69.398340882,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1107ms GEN1:x16:detect
[ 69.404635381,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1120ms presence GEN1:x16:polling
[ 69.404708956,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1120ms training GEN1:x01:recovery
[ 69.417012308,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1144ms GEN1:x01:detect
[ 69.417145693,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1144ms GEN1:x16:detect
[ 69.423440236,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1156ms presence GEN1:x16:polling
[ 69.423519145,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1157ms training GEN1:x01:recovery
[ 69.435817196,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1181ms GEN1:x01:detect
[ 69.435950607,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1181ms GEN1:x16:detect
[ 69.442245027,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1193ms presence GEN1:x16:polling
[ 69.442348722,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1193ms training GEN1:x01:recovery
[ 69.454621975,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1217ms GEN1:x01:detect
[ 69.454755489,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1218ms GEN1:x16:detect
[ 69.461049957,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1230ms presence GEN1:x16:polling
[ 69.461131001,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1230ms training GEN1:x01:recovery
[ 69.473426860,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1254ms GEN1:x01:detect
[ 69.473560149,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1254ms GEN1:x16:detect
[ 69.479854884,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1267ms presence GEN1:x16:polling
[ 69.479937659,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1267ms training GEN1:x01:recovery
[ 69.492231708,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1291ms GEN1:x01:detect
[ 69.492365075,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1291ms GEN1:x16:detect
[ 69.498659675,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1303ms presence GEN1:x16:polling
[ 69.498755558,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1304ms training GEN1:x01:recovery
[ 69.511036482,3] PHB#0001[0:1]: TRACE:0x0000005811000000 1328ms GEN1:x01:recovery
[ 69.511120865,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1328ms GEN1:x01:detect
[ 69.511195634,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1328ms GEN1:x16:detect
[ 70.005464467,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1340ms presence GEN1:x16:polling
[ 70.005552793,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1340ms training GEN1:x01:recovery
[ 70.017841348,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1364ms GEN1:x01:detect
[ 70.017974741,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1365ms GEN1:x16:detect
[ 70.024269386,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1377ms presence GEN1:x16:polling
[ 70.024362556,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1377ms training GEN1:x01:recovery
[ 70.036646214,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1401ms GEN1:x01:detect
[ 70.036779593,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1401ms GEN1:x16:detect
[ 70.043074096,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1414ms presence GEN1:x16:polling
[ 70.043149529,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1414ms training GEN1:x01:recovery
[ 70.055451100,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1438ms GEN1:x01:detect
[ 70.055584444,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1438ms GEN1:x16:detect
[ 70.061878973,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1450ms presence GEN1:x16:polling
[ 70.061986862,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1451ms training GEN1:x01:recovery
[ 70.074255967,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1474ms GEN1:x01:detect
[ 70.074389257,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1475ms GEN1:x16:detect
[ 70.080683797,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1487ms presence GEN1:x16:polling
[ 70.080783790,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1487ms training GEN1:x01:recovery
[ 70.093060729,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1511ms GEN1:x01:detect
[ 70.093194141,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1511ms GEN1:x16:detect
[ 70.099488667,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1524ms presence GEN1:x16:polling
[ 70.099603913,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1524ms training GEN1:x01:recovery
[ 70.111865541,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1548ms GEN1:x01:detect
[ 70.111999002,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1548ms GEN1:x16:detect
[ 70.118293557,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1560ms presence GEN1:x16:polling
[ 70.118400539,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1561ms training GEN1:x01:recovery
[ 70.130670454,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1585ms GEN1:x01:detect
[ 70.130803757,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1585ms GEN1:x16:detect
[ 70.137098393,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1597ms presence GEN1:x16:polling
[ 70.137232409,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1597ms training GEN1:x01:recovery
[ 70.149475182,3] PHB#0001[0:1]: TRACE:0x0000001811000000 1621ms GEN1:x01:detect
[ 70.149557906,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1622ms GEN1:x01:detect
[ 70.149656321,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1622ms GEN1:x16:detect
[ 70.155903574,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1634ms presence GEN1:x16:polling
[ 70.156036600,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1634ms training GEN1:x01:recovery
[ 70.168280099,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1658ms GEN1:x01:detect
[ 70.168413456,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1658ms GEN1:x16:detect
[ 70.174708023,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1671ms presence GEN1:x16:polling
[ 70.174836401,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1671ms training GEN1:x01:recovery
[ 70.187084932,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1695ms GEN1:x01:detect
[ 70.187218329,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1695ms GEN1:x16:detect
[ 70.193512843,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1707ms presence GEN1:x16:polling
[ 70.193641781,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1708ms training GEN1:x01:recovery
[ 70.205889760,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1732ms GEN1:x01:detect
[ 70.206023185,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1732ms GEN1:x16:detect
[ 70.212317734,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1744ms presence GEN1:x16:polling
[ 70.212437094,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1744ms training GEN1:x01:recovery
[ 70.224694599,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1768ms GEN1:x01:detect
[ 70.224828050,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1769ms GEN1:x16:detect
[ 70.231122520,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1781ms presence GEN1:x16:polling
[ 70.231211026,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1781ms training GEN1:x01:recovery
[ 70.243499577,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1805ms GEN1:x01:detect
[ 70.243633060,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1805ms GEN1:x16:detect
[ 70.249927751,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1818ms presence GEN1:x16:polling
[ 70.250011783,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1818ms training GEN1:x01:recovery
[ 70.262304309,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1842ms GEN1:x01:detect
[ 70.262437766,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1842ms GEN1:x16:detect
[ 70.268732324,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1854ms presence GEN1:x16:polling
[ 70.268802004,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1854ms training GEN1:x01:recovery
[ 70.281109139,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1878ms GEN1:x01:detect
[ 70.281242487,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1879ms GEN1:x16:detect
[ 70.287537227,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1891ms presence GEN1:x16:polling
[ 70.287619673,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1891ms training GEN1:x01:recovery
[ 70.299914051,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1915ms GEN1:x01:detect
[ 70.300047469,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1915ms GEN1:x16:detect
[ 70.306341998,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1928ms presence GEN1:x16:polling
[ 70.306420998,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1928ms training GEN1:x01:recovery
[ 70.318718897,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1952ms GEN1:x01:detect
[ 70.318852865,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1952ms GEN1:x16:detect
[ 70.325146824,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1964ms presence GEN1:x16:polling
[ 70.325229610,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1965ms training GEN1:x01:recovery
[ 70.337523676,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1989ms GEN1:x01:detect
[ 70.337657063,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1989ms GEN1:x16:detect
[ 70.343951565,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2001ms presence GEN1:x16:polling
[ 70.344043382,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2001ms training GEN1:x01:recovery
[ 70.356328559,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2025ms GEN1:x01:detect
[ 70.356462051,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2026ms GEN1:x16:detect
[ 70.362756457,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2038ms presence GEN1:x16:polling
[ 70.362857661,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2038ms training GEN1:x01:recovery
[ 70.375133349,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2062ms GEN1:x01:detect
[ 70.375266706,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2062ms GEN1:x16:detect
[ 70.381561304,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2075ms presence GEN1:x16:polling
[ 70.381670010,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2075ms training GEN1:x01:recovery
[ 70.393938213,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2099ms GEN1:x01:detect
[ 70.394071592,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2099ms GEN1:x16:detect
[ 70.400366193,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2111ms presence GEN1:x16:polling
[ 70.400496669,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2112ms training GEN1:x01:recovery
[ 70.412743134,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2136ms GEN1:x01:detect
[ 70.412876522,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2136ms GEN1:x16:detect
[ 70.419171068,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2148ms presence GEN1:x16:polling
[ 70.419288774,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2148ms training GEN1:x01:recovery
[ 70.431548036,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2172ms GEN1:x01:detect
[ 70.431688383,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2173ms GEN1:x16:detect
[ 70.437975878,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2185ms presence GEN1:x16:polling
[ 70.438081199,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2185ms training GEN1:x01:recovery
[ 70.450352786,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2209ms GEN1:x01:detect
[ 70.450487916,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2209ms GEN1:x16:detect
[ 70.456780688,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2222ms presence GEN1:x16:polling
[ 70.456902433,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2222ms training GEN1:x01:recovery
[ 70.469157660,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2246ms GEN1:x01:detect
[ 70.469290996,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2246ms GEN1:x16:detect
[ 70.475585681,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2258ms presence GEN1:x16:polling
[ 70.475736555,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2259ms training GEN1:x01:recovery
[ 70.487962446,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2282ms GEN1:x01:detect
[ 70.488095848,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2283ms GEN1:x16:detect
[ 70.494390444,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2295ms presence GEN1:x16:polling
[ 70.494491419,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2295ms training GEN1:x01:recovery
[ 70.506767344,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2319ms GEN1:x01:detect
[ 70.506900636,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2319ms GEN1:x16:detect
[ 71.001195269,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2332ms presence GEN1:x16:polling
[ 71.001323715,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2332ms training GEN1:x01:recovery
[ 71.013572134,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2356ms GEN1:x01:detect
[ 71.013705481,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2356ms GEN1:x16:detect
[ 71.020000044,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2369ms presence GEN1:x16:polling
[ 71.020082531,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2369ms training GEN1:x01:recovery
[ 71.032377037,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2393ms GEN1:x01:detect
[ 71.032510387,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2393ms GEN1:x16:detect
[ 71.038804924,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2405ms presence GEN1:x16:polling
[ 71.038872805,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2405ms training GEN1:x01:recovery
[ 71.051181848,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2429ms GEN1:x01:detect
[ 71.051315304,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2430ms GEN1:x16:detect
[ 71.057609732,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2442ms presence GEN1:x16:polling
[ 71.057693156,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2442ms training GEN1:x01:recovery
[ 71.069986622,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2466ms GEN1:x01:detect
[ 71.070120070,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2466ms GEN1:x16:detect
[ 71.076414661,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2479ms presence GEN1:x16:polling
[ 71.076494209,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2479ms training GEN1:x01:recovery
[ 71.088791511,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2503ms GEN1:x01:detect
[ 71.088924899,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2503ms GEN1:x16:detect
[ 71.095219434,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2515ms presence GEN1:x16:polling
[ 71.095301451,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2516ms training GEN1:x01:recovery
[ 71.107596348,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2540ms GEN1:x01:detect
[ 71.107729794,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2540ms GEN1:x16:detect
[ 71.114024438,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2552ms presence GEN1:x16:polling
[ 71.114116108,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2552ms training GEN1:x01:recovery
[ 71.126401299,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2576ms GEN1:x01:detect
[ 71.126534550,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2577ms GEN1:x16:detect
[ 71.132829172,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2589ms presence GEN1:x16:polling
[ 71.132910499,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2589ms training GEN1:x01:recovery
[ 71.145206045,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2613ms GEN1:x01:detect
[ 71.145339445,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2613ms GEN1:x16:detect
[ 71.151633945,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2626ms presence GEN1:x16:polling
[ 71.151736072,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2626ms training GEN1:x01:recovery
[ 71.164010915,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2650ms GEN1:x01:detect
[ 71.164144241,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2650ms GEN1:x16:detect
[ 71.170438842,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2662ms presence GEN1:x16:polling
[ 71.170519683,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2662ms training GEN1:x01:recovery
[ 71.182815806,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2687ms GEN1:x01:detect
[ 71.182949127,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2687ms GEN1:x16:detect
[ 71.189243607,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2699ms presence GEN1:x16:polling
[ 71.189336792,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2699ms training GEN1:x01:recovery
[ 71.201620582,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2723ms GEN1:x01:detect
[ 71.201754001,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2723ms GEN1:x16:detect
[ 71.208048483,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2736ms presence GEN1:x16:polling
[ 71.208135404,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2736ms training GEN1:x01:recovery
[ 71.220425361,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2760ms GEN1:x01:detect
[ 71.220558771,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2760ms GEN1:x16:detect
[ 71.226853428,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2773ms presence GEN1:x16:polling
[ 71.226959244,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2773ms training GEN1:x01:recovery
[ 71.239230291,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2797ms GEN1:x01:detect
[ 71.239363690,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2797ms GEN1:x16:detect
[ 71.245658183,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2809ms presence GEN1:x16:polling
[ 71.245768103,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2809ms training GEN1:x01:recovery
[ 71.258035145,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2833ms GEN1:x01:detect
[ 71.258168437,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2834ms GEN1:x16:detect
[ 71.264463081,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2846ms presence GEN1:x16:polling
[ 71.264559640,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2846ms training GEN1:x01:recovery
[ 71.276839922,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2870ms GEN1:x01:detect
[ 71.277002428,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2870ms GEN1:x16:detect
[ 71.283268053,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2883ms presence GEN1:x16:polling
[ 71.283377609,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2883ms training GEN1:x01:recovery
[ 71.295644769,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2907ms GEN1:x01:detect
[ 71.295778180,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2907ms GEN1:x16:detect
[ 71.302072689,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2919ms presence GEN1:x16:polling
[ 71.302184906,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2920ms training GEN1:x01:recovery
[ 71.314449683,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2944ms GEN1:x01:detect
[ 71.314582989,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2944ms GEN1:x16:detect
[ 71.320877652,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2956ms presence GEN1:x16:polling
[ 71.320987778,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2956ms training GEN1:x01:recovery
[ 71.333254467,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2980ms GEN1:x01:detect
[ 71.333413725,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2981ms GEN1:x16:detect
[ 71.339682530,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2993ms presence GEN1:x16:polling
[ 71.339809524,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2993ms training GEN1:x01:recovery
[ 71.343063161,3] PHB#0001[0:1]: TRACE: Timeout waiting for link up.
[ 71.343666315,7] PHB#0001[0:1]: LINK: Start polling
[ 71.344185975,7] PHB#0001[0:1]: LINK: Electrical link detected
[ 73.346097450,3] PHB#0001[0:1]: LINK: Timeout waiting for link up
[ 73.346202304,7] PHB#0001[0:1]: LINK: DLP train control: 0x0000001101000000
[ 73.346722536,7] PHB#0001[0:1]: CRESET: Starts
[ 73.351935589,7] PHB#0001[0:1]: CRESET: No pending transactions
[ 73.357069171,7] PHB#0001[0:1]: CRESET: Reinitialization
[ 73.357070062,7] PHB#0001[0:1]: Initializing PHB4...
[ 73.357071052,7] PHB#0001[0:1]: Default system config: 0x4210000040200000
[ 73.357072421,7] PHB#0001[0:1]: New system config : 0x4210000040200000
[ 73.357073733,7] PHB#0001[0:1]: Initial PHB CRESET is 0xe000000000000000
[ 73.357075152,7] PHB#0001[0:1]: Waiting for DLP PG reset to complete...
[ 73.357595372,7] PHB#0001[0:1]: Reset state SRC_ID: 0ff8000000000000
[ 73.357800212,7] PHB#0001[0:1]: Initialization complete
[ 73.409008026,7] PHB#0001[0:1]: FRESET: Starts
[ 73.409008953,7] PHB#0001[0:1]: FRESET: Prepare for link down
[ 73.409010161,7] PHB#0001[0:1]: FRESET: Assert
[ 74.025018332,7] PHB#0001[0:1]: FRESET: Deassert
[ 74.025023389,3] PHB#0001[0:1]: TRACE:0x0000001101000000 0ms GEN1:x16:detect
[ 74.034225370,3] PHB#0001[0:1]: TRACE:0x0000102101000000 17ms presence GEN1:x16:polling
[ 74.034481438,3] PHB#0001[0:1]: TRACE:0x0000183101000000 18ms training GEN1:x16:config
[ 74.034536924,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 18ms training GEN1:x01:recovery
[ 74.046823229,3] PHB#0001[0:1]: TRACE:0x0000001011000000 42ms GEN1:x01:detect
[ 74.046956628,3] PHB#0001[0:1]: TRACE:0x0000001101000000 42ms GEN1:x16:detect
[ 74.053251130,3] PHB#0001[0:1]: TRACE:0x0000102101000000 55ms presence GEN1:x16:polling
[ 74.053334258,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 55ms training GEN1:x01:recovery
[ 74.065628083,3] PHB#0001[0:1]: TRACE:0x0000001011000000 79ms GEN1:x01:detect
[ 74.065761554,3] PHB#0001[0:1]: TRACE:0x0000001101000000 79ms GEN1:x16:detect
[ 74.072055990,3] PHB#0001[0:1]: TRACE:0x0000102101000000 91ms presence GEN1:x16:polling
[ 74.072143310,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 92ms training GEN1:x01:recovery
[ 74.084433195,3] PHB#0001[0:1]: TRACE:0x0000001011000000 116ms GEN1:x01:detect
[ 74.084567009,3] PHB#0001[0:1]: TRACE:0x0000001101000000 116ms GEN1:x16:detect
[ 74.090861321,3] PHB#0001[0:1]: TRACE:0x0000102101000000 128ms presence GEN1:x16:polling
[ 74.090932750,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 128ms training GEN1:x01:recovery
[ 74.103238375,3] PHB#0001[0:1]: TRACE:0x0000001011000000 152ms GEN1:x01:detect
[ 74.103371314,3] PHB#0001[0:1]: TRACE:0x0000001101000000 153ms GEN1:x16:detect
[ 74.109666044,3] PHB#0001[0:1]: TRACE:0x0000102101000000 165ms presence GEN1:x16:polling
[ 74.109751298,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 165ms training GEN1:x01:recovery
[ 74.122042840,3] PHB#0001[0:1]: TRACE:0x0000001011000000 189ms GEN1:x01:detect
[ 74.122176442,3] PHB#0001[0:1]: TRACE:0x0000001101000000 189ms GEN1:x16:detect
[ 74.128470858,3] PHB#0001[0:1]: TRACE:0x0000102101000000 202ms presence GEN1:x16:polling
[ 74.128550810,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 202ms training GEN1:x01:recovery
[ 74.140847649,3] PHB#0001[0:1]: TRACE:0x0000001011000000 226ms GEN1:x01:detect
[ 74.140981157,3] PHB#0001[0:1]: TRACE:0x0000001101000000 226ms GEN1:x16:detect
[ 74.147275575,3] PHB#0001[0:1]: TRACE:0x0000102101000000 238ms presence GEN1:x16:polling
[ 74.147371395,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 238ms training GEN1:x01:recovery
[ 74.159652536,3] PHB#0001[0:1]: TRACE:0x0000001011000000 262ms GEN1:x01:detect
[ 74.159786056,3] PHB#0001[0:1]: TRACE:0x0000001101000000 263ms GEN1:x16:detect
[ 74.166080715,3] PHB#0001[0:1]: TRACE:0x0000102101000000 275ms presence GEN1:x16:polling
[ 74.166161380,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 275ms training GEN1:x01:recovery
[ 74.178457337,3] PHB#0001[0:1]: TRACE:0x0000001011000000 299ms GEN1:x01:detect
[ 74.178590935,3] PHB#0001[0:1]: TRACE:0x0000001101000000 299ms GEN1:x16:detect
[ 74.184885468,3] PHB#0001[0:1]: TRACE:0x0000102101000000 312ms presence GEN1:x16:polling
[ 74.184969766,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 312ms training GEN1:x01:recovery
[ 74.197262180,3] PHB#0001[0:1]: TRACE:0x0000001011000000 336ms GEN1:x01:detect
[ 74.197407446,3] PHB#0001[0:1]: TRACE:0x0000001101000000 336ms GEN1:x16:detect
[ 74.203690347,3] PHB#0001[0:1]: TRACE:0x0000102101000000 348ms presence GEN1:x16:polling
[ 74.203790347,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 349ms training GEN1:x01:recovery
[ 74.216066948,3] PHB#0001[0:1]: TRACE:0x0000001011000000 373ms GEN1:x01:detect
[ 74.216200345,3] PHB#0001[0:1]: TRACE:0x0000001101000000 373ms GEN1:x16:detect
[ 74.222495275,3] PHB#0001[0:1]: TRACE:0x0000102101000000 385ms presence GEN1:x16:polling
[ 74.222597809,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 385ms training GEN1:x01:recovery
[ 74.234871966,3] PHB#0001[0:1]: TRACE:0x0000001011000000 409ms GEN1:x01:detect
[ 74.235005675,3] PHB#0001[0:1]: TRACE:0x0000001101000000 410ms GEN1:x16:detect
[ 74.241300036,3] PHB#0001[0:1]: TRACE:0x0000102101000000 422ms presence GEN1:x16:polling
[ 74.241397323,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 422ms training GEN1:x01:recovery
[ 74.253677024,3] PHB#0001[0:1]: TRACE:0x0000001011000000 446ms GEN1:x01:detect
[ 74.253810045,3] PHB#0001[0:1]: TRACE:0x0000001101000000 446ms GEN1:x16:detect
[ 74.260105085,3] PHB#0001[0:1]: TRACE:0x0000102101000000 459ms presence GEN1:x16:polling
[ 74.260220871,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 459ms training GEN1:x01:recovery
[ 74.272481611,3] PHB#0001[0:1]: TRACE:0x0000001011000000 483ms GEN1:x01:detect
[ 74.272615254,3] PHB#0001[0:1]: TRACE:0x0000001101000000 483ms GEN1:x16:detect
[ 74.278909502,3] PHB#0001[0:1]: TRACE:0x0000102101000000 495ms presence GEN1:x16:polling
[ 74.279064803,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 496ms training GEN1:x01:recovery
[ 74.291286436,3] PHB#0001[0:1]: TRACE:0x0000001011000000 520ms GEN1:x01:detect
[ 74.291428257,3] PHB#0001[0:1]: TRACE:0x0000001101000000 520ms GEN1:x16:detect
[ 74.297714367,3] PHB#0001[0:1]: TRACE:0x0000101101000000 532ms presence GEN1:x16:detect
[ 74.297822628,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 532ms training GEN1:x01:recovery
[ 74.310091448,3] PHB#0001[0:1]: TRACE:0x0000001011000000 556ms GEN1:x01:detect
[ 74.310224568,3] PHB#0001[0:1]: TRACE:0x0000001101000000 557ms GEN1:x16:detect
[ 74.316519239,3] PHB#0001[0:1]: TRACE:0x0000102101000000 569ms presence GEN1:x16:polling
[ 74.316621052,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 569ms training GEN1:x01:recovery
[ 74.328896323,3] PHB#0001[0:1]: TRACE:0x0000001011000000 593ms GEN1:x01:detect
[ 74.329029422,3] PHB#0001[0:1]: TRACE:0x0000001101000000 593ms GEN1:x16:detect
[ 74.335324022,3] PHB#0001[0:1]: TRACE:0x0000102101000000 606ms presence GEN1:x16:polling
[ 74.335420363,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 606ms training GEN1:x01:recovery
[ 74.347701000,3] PHB#0001[0:1]: TRACE:0x0000001011000000 630ms GEN1:x01:detect
[ 74.347862311,3] PHB#0001[0:1]: TRACE:0x0000001101000000 630ms GEN1:x16:detect
[ 74.354128986,3] PHB#0001[0:1]: TRACE:0x0000102101000000 642ms presence GEN1:x16:polling
[ 74.354248179,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 643ms training GEN1:x01:recovery
[ 74.366506186,3] PHB#0001[0:1]: TRACE:0x0000001011000000 666ms GEN1:x01:detect
[ 74.366639522,3] PHB#0001[0:1]: TRACE:0x0000001101000000 667ms GEN1:x16:detect
[ 74.372933738,3] PHB#0001[0:1]: TRACE:0x0000102101000000 679ms presence GEN1:x16:polling
[ 74.373017462,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 679ms training GEN1:x01:recovery
[ 74.385310803,3] PHB#0001[0:1]: TRACE:0x0000001011000000 703ms GEN1:x01:detect
[ 74.385443904,3] PHB#0001[0:1]: TRACE:0x0000001101000000 703ms GEN1:x16:detect
[ 74.391739021,3] PHB#0001[0:1]: TRACE:0x0000102101000000 716ms presence GEN1:x16:polling
[ 74.391819645,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 716ms training GEN1:x01:recovery
[ 74.404115761,3] PHB#0001[0:1]: TRACE:0x0000001011000000 740ms GEN1:x01:detect
[ 74.404248994,3] PHB#0001[0:1]: TRACE:0x0000001101000000 740ms GEN1:x16:detect
[ 74.410543587,3] PHB#0001[0:1]: TRACE:0x0000102101000000 752ms presence GEN1:x16:polling
[ 74.410628827,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 753ms training GEN1:x01:recovery
[ 74.422920237,3] PHB#0001[0:1]: TRACE:0x0000001011000000 777ms GEN1:x01:detect
[ 74.423054143,3] PHB#0001[0:1]: TRACE:0x0000001101000000 777ms GEN1:x16:detect
[ 74.429348381,3] PHB#0001[0:1]: TRACE:0x0000102101000000 789ms presence GEN1:x16:polling
[ 74.429424013,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 789ms training GEN1:x01:recovery
[ 74.441725044,3] PHB#0001[0:1]: TRACE:0x0000001011000000 813ms GEN1:x01:detect
[ 74.441858904,3] PHB#0001[0:1]: TRACE:0x0000001101000000 814ms GEN1:x16:detect
[ 74.448153421,3] PHB#0001[0:1]: TRACE:0x0000102101000000 826ms presence GEN1:x16:polling
[ 74.448232844,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 826ms training GEN1:x01:recovery
[ 74.460530225,3] PHB#0001[0:1]: TRACE:0x0000001011000000 850ms GEN1:x01:detect
[ 74.460663451,3] PHB#0001[0:1]: TRACE:0x0000001101000000 850ms GEN1:x16:detect
[ 74.466958020,3] PHB#0001[0:1]: TRACE:0x0000102101000000 863ms presence GEN1:x16:polling
[ 74.467036210,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 863ms training GEN1:x01:recovery
[ 74.479335063,3] PHB#0001[0:1]: TRACE:0x0000001011000000 887ms GEN1:x01:detect
[ 74.479468334,3] PHB#0001[0:1]: TRACE:0x0000001101000000 887ms GEN1:x16:detect
[ 74.485762763,3] PHB#0001[0:1]: TRACE:0x0000102101000000 899ms presence GEN1:x16:polling
[ 74.485842151,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 900ms training GEN1:x01:recovery
[ 74.498139597,3] PHB#0001[0:1]: TRACE:0x0000001011000000 924ms GEN1:x01:detect
[ 74.498272965,3] PHB#0001[0:1]: TRACE:0x0000001101000000 924ms GEN1:x16:detect
[ 74.504568237,3] PHB#0001[0:1]: TRACE:0x0000102101000000 936ms presence GEN1:x16:polling
[ 74.504647911,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 936ms training GEN1:x01:recovery
[ 75.004945024,3] PHB#0001[0:1]: TRACE:0x0000001011000000 960ms GEN1:x01:detect
[ 75.005078046,3] PHB#0001[0:1]: TRACE:0x0000001101000000 961ms GEN1:x16:detect
[ 75.011372419,3] PHB#0001[0:1]: TRACE:0x0000102101000000 973ms presence GEN1:x16:polling
[ 75.011471608,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 973ms training GEN1:x01:recovery
[ 75.023749602,3] PHB#0001[0:1]: TRACE:0x0000001011000000 997ms GEN1:x01:detect
[ 75.023883195,3] PHB#0001[0:1]: TRACE:0x0000001101000000 997ms GEN1:x16:detect
[ 75.030177350,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1010ms presence GEN1:x16:polling
[ 75.030260561,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1010ms training GEN1:x01:recovery
[ 75.042554297,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1034ms GEN1:x01:detect
[ 75.042687671,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1034ms GEN1:x16:detect
[ 75.048982261,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1046ms presence GEN1:x16:polling
[ 75.049064423,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1046ms training GEN1:x01:recovery
[ 75.061359255,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1070ms GEN1:x01:detect
[ 75.061492515,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1071ms GEN1:x16:detect
[ 75.067787067,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1083ms presence GEN1:x16:polling
[ 75.067905030,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1083ms training GEN1:x01:recovery
[ 75.080163958,3] PHB#0001[0:1]: TRACE:0x0000001811000000 1107ms GEN1:x01:detect
[ 75.080267565,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1107ms GEN1:x01:detect
[ 75.080374347,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1108ms GEN1:x16:detect
[ 75.086591899,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1120ms presence GEN1:x16:polling
[ 75.086692517,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1120ms training GEN1:x01:recovery
[ 75.098968866,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1144ms GEN1:x01:detect
[ 75.099102317,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1144ms GEN1:x16:detect
[ 75.105396845,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1156ms presence GEN1:x16:polling
[ 75.105501384,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1157ms training GEN1:x01:recovery
[ 75.117773676,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1181ms GEN1:x01:detect
[ 75.117907078,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1181ms GEN1:x16:detect
[ 75.124201587,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1193ms presence GEN1:x16:polling
[ 75.124339444,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1193ms training GEN1:x01:recovery
[ 75.136578551,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1217ms GEN1:x01:detect
[ 75.136711886,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1218ms GEN1:x16:detect
[ 75.143006411,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1230ms presence GEN1:x16:polling
[ 75.143115475,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1230ms training GEN1:x01:recovery
[ 75.155383405,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1254ms GEN1:x01:detect
[ 75.155516696,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1254ms GEN1:x16:detect
[ 75.161811296,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1267ms presence GEN1:x16:polling
[ 75.161913773,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1267ms training GEN1:x01:recovery
[ 75.174188154,3] PHB#0001[0:1]: TRACE:0x0000001811000000 1291ms GEN1:x01:detect
[ 75.174326620,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1291ms GEN1:x16:detect
[ 75.180616128,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1303ms presence GEN1:x16:polling
[ 75.180746533,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1304ms training GEN1:x01:recovery
[ 75.192993024,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1328ms GEN1:x01:detect
[ 75.193126364,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1328ms GEN1:x16:detect
[ 75.199420954,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1340ms presence GEN1:x16:polling
[ 75.199548017,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1340ms training GEN1:x01:recovery
[ 75.211797871,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1364ms GEN1:x01:detect
[ 75.211931338,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1365ms GEN1:x16:detect
[ 75.218225763,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1377ms presence GEN1:x16:polling
[ 75.218324826,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1377ms training GEN1:x01:recovery
[ 75.230602782,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1401ms GEN1:x01:detect
[ 75.230736166,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1401ms GEN1:x16:detect
[ 75.237030632,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1414ms presence GEN1:x16:polling
[ 75.237111885,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1414ms training GEN1:x01:recovery
[ 75.249407540,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1438ms GEN1:x01:detect
[ 75.249540915,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1438ms GEN1:x16:detect
[ 75.255835436,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1450ms presence GEN1:x16:polling
[ 75.255920860,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1450ms training GEN1:x01:recovery
[ 75.268212439,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1474ms GEN1:x01:detect
[ 75.268345765,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1475ms GEN1:x16:detect
[ 75.274640333,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1487ms presence GEN1:x16:polling
[ 75.274712192,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1487ms training GEN1:x01:recovery
[ 75.287017289,3] PHB#0001[0:1]: TRACE:0x0000001811000000 1511ms GEN1:x01:detect
[ 75.287108346,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1511ms GEN1:x01:detect
[ 75.287163638,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1511ms GEN1:x16:detect
[ 75.293445235,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1524ms presence GEN1:x16:polling
[ 75.293519334,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1524ms training GEN1:x01:recovery
[ 75.305822124,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1548ms GEN1:x01:detect
[ 75.305955627,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1548ms GEN1:x16:detect
[ 75.312250034,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1560ms presence GEN1:x16:polling
[ 75.312326405,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1561ms training GEN1:x01:recovery
[ 75.324626946,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1585ms GEN1:x01:detect
[ 75.324760318,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1585ms GEN1:x16:detect
[ 75.331054843,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1597ms presence GEN1:x16:polling
[ 75.331132637,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1597ms training GEN1:x01:recovery
[ 75.343431784,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1621ms GEN1:x01:detect
[ 75.343565209,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1622ms GEN1:x16:detect
[ 75.349859740,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1634ms presence GEN1:x16:polling
[ 75.350014291,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1634ms training GEN1:x01:recovery
[ 75.362236667,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1658ms GEN1:x01:detect
[ 75.362369973,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1658ms GEN1:x16:detect
[ 75.368664584,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1671ms presence GEN1:x16:polling
[ 75.368779090,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1671ms training GEN1:x01:recovery
[ 75.381041477,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1695ms GEN1:x01:detect
[ 75.381174868,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1695ms GEN1:x16:detect
[ 75.387469415,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1707ms presence GEN1:x16:polling
[ 75.387580306,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1708ms training GEN1:x01:recovery
[ 75.399846286,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1732ms GEN1:x01:detect
[ 75.399979707,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1732ms GEN1:x16:detect
[ 75.406274211,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1744ms presence GEN1:x16:polling
[ 75.406409240,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1744ms training GEN1:x01:recovery
[ 75.418651211,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1768ms GEN1:x01:detect
[ 75.418784626,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1769ms GEN1:x16:detect
[ 75.425079112,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1781ms presence GEN1:x16:polling
[ 75.425183021,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1781ms training GEN1:x01:recovery
[ 75.437456023,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1805ms GEN1:x01:detect
[ 75.437589450,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1805ms GEN1:x16:detect
[ 75.443883945,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1818ms presence GEN1:x16:polling
[ 75.444012894,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1818ms training GEN1:x01:recovery
[ 75.456260934,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1842ms GEN1:x01:detect
[ 75.456394188,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1842ms GEN1:x16:detect
[ 75.462688823,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1854ms presence GEN1:x16:polling
[ 75.462839411,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1855ms training GEN1:x01:recovery
[ 75.475065645,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1878ms GEN1:x01:detect
[ 75.475199231,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1879ms GEN1:x16:detect
[ 75.481493673,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1891ms presence GEN1:x16:polling
[ 75.481621178,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1891ms training GEN1:x01:recovery
[ 75.493870665,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1915ms GEN1:x01:detect
[ 75.494003859,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1915ms GEN1:x16:detect
[ 75.500298480,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1928ms presence GEN1:x16:polling
[ 75.500400721,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1928ms training GEN1:x01:recovery
[ 76.000675448,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1952ms GEN1:x01:detect
[ 76.000808790,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1952ms GEN1:x16:detect
[ 76.007103390,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1965ms presence GEN1:x16:polling
[ 76.007176378,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1965ms training GEN1:x01:recovery
[ 76.019480230,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1989ms GEN1:x01:detect
[ 76.019613684,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1989ms GEN1:x16:detect
[ 76.025908152,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2001ms presence GEN1:x16:polling
[ 76.026005586,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2001ms training GEN1:x01:recovery
[ 76.038285129,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2025ms GEN1:x01:detect
[ 76.038418603,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2026ms GEN1:x16:detect
[ 76.044713051,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2038ms presence GEN1:x16:polling
[ 76.044779737,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2038ms training GEN1:x01:recovery
[ 76.057089942,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2062ms GEN1:x01:detect
[ 76.057223317,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2062ms GEN1:x16:detect
[ 76.063517859,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2075ms presence GEN1:x16:polling
[ 76.063594264,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2075ms training GEN1:x01:recovery
[ 76.075894786,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2099ms GEN1:x01:detect
[ 76.076028164,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2099ms GEN1:x16:detect
[ 76.082322638,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2111ms presence GEN1:x16:polling
[ 76.082405250,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2112ms training GEN1:x01:recovery
[ 76.094699636,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2136ms GEN1:x01:detect
[ 76.094833056,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2136ms GEN1:x16:detect
[ 76.101127529,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2148ms presence GEN1:x16:polling
[ 76.101206951,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2148ms training GEN1:x01:recovery
[ 76.113504436,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2172ms GEN1:x01:detect
[ 76.113637764,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2173ms GEN1:x16:detect
[ 76.119932406,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2185ms presence GEN1:x16:polling
[ 76.120008701,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2185ms training GEN1:x01:recovery
[ 76.132309286,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2209ms GEN1:x01:detect
[ 76.132442695,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2209ms GEN1:x16:detect
[ 76.138737290,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2222ms presence GEN1:x16:polling
[ 76.138845831,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2222ms training GEN1:x01:recovery
[ 76.151114134,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2246ms GEN1:x01:detect
[ 76.151247506,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2246ms GEN1:x16:detect
[ 76.157542018,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2258ms presence GEN1:x16:polling
[ 76.157620267,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2258ms training GEN1:x01:recovery
[ 76.169918954,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2282ms GEN1:x01:detect
[ 76.170052452,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2283ms GEN1:x16:detect
[ 76.176346875,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2295ms presence GEN1:x16:polling
[ 76.176426684,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2295ms training GEN1:x01:recovery
[ 76.188723888,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2319ms GEN1:x01:detect
[ 76.188857356,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2319ms GEN1:x16:detect
[ 76.195151772,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2332ms presence GEN1:x16:polling
[ 76.195258396,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2332ms training GEN1:x01:recovery
[ 76.207528731,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2356ms GEN1:x01:detect
[ 76.207662060,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2356ms GEN1:x16:detect
[ 76.213956685,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2369ms presence GEN1:x16:polling
[ 76.214050999,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2369ms training GEN1:x01:recovery
[ 76.226333501,3] PHB#0001[0:1]: TRACE:0x0000001811000000 2393ms GEN1:x01:detect
[ 76.226474956,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2393ms GEN1:x16:detect
[ 76.232761425,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2405ms presence GEN1:x16:polling
[ 76.232884024,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2405ms training GEN1:x01:recovery
[ 76.245138316,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2429ms GEN1:x01:detect
[ 76.245271706,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2430ms GEN1:x16:detect
[ 76.251566282,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2442ms presence GEN1:x16:polling
[ 76.251697550,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2442ms training GEN1:x01:recovery
[ 76.263943182,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2466ms GEN1:x01:detect
[ 76.264076698,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2466ms GEN1:x16:detect
[ 76.270371156,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2479ms presence GEN1:x16:polling
[ 76.270461478,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2479ms training GEN1:x01:recovery
[ 76.282748065,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2503ms GEN1:x01:detect
[ 76.282881458,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2503ms GEN1:x16:detect
[ 76.289176038,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2515ms presence GEN1:x16:polling
[ 76.289280227,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2516ms training GEN1:x01:recovery
[ 76.301552906,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2540ms GEN1:x01:detect
[ 76.301686329,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2540ms GEN1:x16:detect
[ 76.307980820,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2552ms presence GEN1:x16:polling
[ 76.308086931,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2552ms training GEN1:x01:recovery
[ 76.320357694,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2576ms GEN1:x01:detect
[ 76.320491214,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2577ms GEN1:x16:detect
[ 76.326785722,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2589ms presence GEN1:x16:polling
[ 76.326898006,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2589ms training GEN1:x01:recovery
[ 76.339162691,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2613ms GEN1:x01:detect
[ 76.339295922,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2613ms GEN1:x16:detect
[ 76.345590509,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2626ms presence GEN1:x16:polling
[ 76.345682599,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2626ms training GEN1:x01:recovery
[ 76.357967476,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2650ms GEN1:x01:detect
[ 76.358100906,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2650ms GEN1:x16:detect
[ 76.364395296,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2662ms presence GEN1:x16:polling
[ 76.364508905,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2663ms training GEN1:x01:recovery
[ 76.376772294,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2687ms GEN1:x01:detect
[ 76.376905631,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2687ms GEN1:x16:detect
[ 76.383200280,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2699ms presence GEN1:x16:polling
[ 76.383281348,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2699ms training GEN1:x01:recovery
[ 76.395577099,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2723ms GEN1:x01:detect
[ 76.395710443,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2723ms GEN1:x16:detect
[ 76.402005018,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2736ms presence GEN1:x16:polling
[ 76.402072340,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2736ms training GEN1:x01:recovery
[ 76.414381938,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2760ms GEN1:x01:detect
[ 76.414515352,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2760ms GEN1:x16:detect
[ 76.420809894,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2773ms presence GEN1:x16:polling
[ 76.420886917,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2773ms training GEN1:x01:recovery
[ 76.433186773,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2797ms GEN1:x01:detect
[ 76.433320229,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2797ms GEN1:x16:detect
[ 76.439614705,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2809ms presence GEN1:x16:polling
[ 76.439694070,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2809ms training GEN1:x01:recovery
[ 76.451991649,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2833ms GEN1:x01:detect
[ 76.452125020,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2834ms GEN1:x16:detect
[ 76.458419562,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2846ms presence GEN1:x16:polling
[ 76.458493297,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2846ms training GEN1:x01:recovery
[ 76.470796424,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2870ms GEN1:x01:detect
[ 76.470929955,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2870ms GEN1:x16:detect
[ 76.477224377,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2883ms presence GEN1:x16:polling
[ 76.477323786,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2883ms training GEN1:x01:recovery
[ 76.489601373,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2907ms GEN1:x01:detect
[ 76.489734710,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2907ms GEN1:x16:detect
[ 76.496029224,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2919ms presence GEN1:x16:polling
[ 76.496113575,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2920ms training GEN1:x01:recovery
[ 76.508406149,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2944ms GEN1:x01:detect
[ 76.508539548,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2944ms GEN1:x16:detect
[ 77.002834173,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2956ms presence GEN1:x16:polling
[ 77.002907884,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2956ms training GEN1:x01:recovery
[ 77.015210948,3] PHB#0001[0:1]: TRACE:0x0000005811000000 2980ms GEN1:x01:recovery
[ 77.015283016,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2980ms GEN1:x01:detect
[ 77.015344440,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2981ms GEN1:x16:detect
[ 77.021638930,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2993ms presence GEN1:x16:polling
[ 77.021722726,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2993ms training GEN1:x01:recovery
[ 77.025019539,3] PHB#0001[0:1]: TRACE: Timeout waiting for link up.
[ 77.025606006,7] PHB#0001[0:1]: LINK: Start polling
[ 77.026125660,7] PHB#0001[0:1]: LINK: Electrical link detected
[ 79.028038394,3] PHB#0001[0:1]: LINK: Timeout waiting for link up
[ 79.028098741,7] PHB#0001[0:1]: LINK: DLP train control: 0x0000001101000000
[ 79.028619016,7] PHB#0001[0:1]: CRESET: Starts
[ 79.033781724,7] PHB#0001[0:1]: CRESET: No pending transactions
[ 79.038911926,7] PHB#0001[0:1]: CRESET: Reinitialization
[ 79.038912728,7] PHB#0001[0:1]: Initializing PHB4...
[ 79.038913761,7] PHB#0001[0:1]: Default system config: 0x4210000040200000
[ 79.038915147,7] PHB#0001[0:1]: New system config : 0x4210000040200000
[ 79.038916330,7] PHB#0001[0:1]: Initial PHB CRESET is 0xe000000000000000
[ 79.038917740,7] PHB#0001[0:1]: Waiting for DLP PG reset to complete...
[ 79.039438029,7] PHB#0001[0:1]: Reset state SRC_ID: 0ff8000000000000
[ 79.039642897,7] PHB#0001[0:1]: Initialization complete
[ 79.090850633,7] PHB#0001[0:1]: FRESET: Starts
[ 79.090851597,7] PHB#0001[0:1]: FRESET: Prepare for link down
[ 79.090852857,7] PHB#0001[0:1]: FRESET: Assert
[ 79.218860955,7] PHB#0001[0:1]: FRESET: Deassert
[ 79.218865833,3] PHB#0001[0:1]: TRACE:0x0000001101000000 0ms GEN1:x16:detect
[ 79.228066113,3] PHB#0001[0:1]: TRACE:0x0000102101000000 17ms presence GEN1:x16:polling
[ 79.228323716,3] PHB#0001[0:1]: TRACE:0x0000183101000000 18ms training GEN1:x16:config
[ 79.228402876,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 18ms training GEN1:x01:recovery
[ 79.240665781,3] PHB#0001[0:1]: TRACE:0x0000001011000000 42ms GEN1:x01:detect
[ 79.240799202,3] PHB#0001[0:1]: TRACE:0x0000001101000000 42ms GEN1:x16:detect
[ 79.247093765,3] PHB#0001[0:1]: TRACE:0x0000102101000000 55ms presence GEN1:x16:polling
[ 79.247178377,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 55ms training GEN1:x01:recovery
[ 79.259471058,3] PHB#0001[0:1]: TRACE:0x0000045811000000 79ms GEN1:x01:recovery
[ 79.259558563,3] PHB#0001[0:1]: TRACE:0x0000001011000000 79ms GEN1:x01:detect
[ 79.259605735,3] PHB#0001[0:1]: TRACE:0x0000001101000000 79ms GEN1:x16:detect
[ 79.265898608,3] PHB#0001[0:1]: TRACE:0x0000102101000000 91ms presence GEN1:x16:polling
[ 79.265977686,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 92ms training GEN1:x01:recovery
[ 79.278275766,3] PHB#0001[0:1]: TRACE:0x0000005811000000 116ms GEN1:x01:recovery
[ 79.278353675,3] PHB#0001[0:1]: TRACE:0x0000001011000000 116ms GEN1:x01:detect
[ 79.278409600,3] PHB#0001[0:1]: TRACE:0x0000001101000000 116ms GEN1:x16:detect
[ 79.284703963,3] PHB#0001[0:1]: TRACE:0x0000102101000000 128ms presence GEN1:x16:polling
[ 79.284788350,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 128ms training GEN1:x01:recovery
[ 79.297080862,3] PHB#0001[0:1]: TRACE:0x0000001011000000 152ms GEN1:x01:detect
[ 79.297214364,3] PHB#0001[0:1]: TRACE:0x0000001101000000 153ms GEN1:x16:detect
[ 79.303508574,3] PHB#0001[0:1]: TRACE:0x0000102101000000 165ms presence GEN1:x16:polling
[ 79.303589531,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 165ms training GEN1:x01:recovery
[ 79.315885762,3] PHB#0001[0:1]: TRACE:0x0000001011000000 189ms GEN1:x01:detect
[ 79.316019024,3] PHB#0001[0:1]: TRACE:0x0000001101000000 189ms GEN1:x16:detect
[ 79.322313733,3] PHB#0001[0:1]: TRACE:0x0000102101000000 202ms presence GEN1:x16:polling
[ 79.322399384,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 202ms training GEN1:x01:recovery
[ 79.334690275,3] PHB#0001[0:1]: TRACE:0x0000001011000000 226ms GEN1:x01:detect
[ 79.334823813,3] PHB#0001[0:1]: TRACE:0x0000001101000000 226ms GEN1:x16:detect
[ 79.341118082,3] PHB#0001[0:1]: TRACE:0x0000101101000000 238ms presence GEN1:x16:detect
[ 79.341203511,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 238ms training GEN1:x01:recovery
[ 79.353495553,3] PHB#0001[0:1]: TRACE:0x0000001011000000 262ms GEN1:x01:detect
[ 79.353628757,3] PHB#0001[0:1]: TRACE:0x0000001101000000 263ms GEN1:x16:detect
[ 79.359923189,3] PHB#0001[0:1]: TRACE:0x0000102101000000 275ms presence GEN1:x16:polling
[ 79.360011613,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 275ms training GEN1:x01:recovery
[ 79.372299997,3] PHB#0001[0:1]: TRACE:0x0000001011000000 299ms GEN1:x01:detect
[ 79.372433586,3] PHB#0001[0:1]: TRACE:0x0000001101000000 299ms GEN1:x16:detect
[ 79.378728088,3] PHB#0001[0:1]: TRACE:0x0000102101000000 312ms presence GEN1:x16:polling
[ 79.378825086,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 312ms training GEN1:x01:recovery
[ 79.391104826,3] PHB#0001[0:1]: TRACE:0x0000001011000000 336ms GEN1:x01:detect
[ 79.391238085,3] PHB#0001[0:1]: TRACE:0x0000001101000000 336ms GEN1:x16:detect
[ 79.397532650,3] PHB#0001[0:1]: TRACE:0x0000102101000000 348ms presence GEN1:x16:polling
[ 79.397618987,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 349ms training GEN1:x01:recovery
[ 79.409910007,3] PHB#0001[0:1]: TRACE:0x0000001011000000 373ms GEN1:x01:detect
[ 79.410043164,3] PHB#0001[0:1]: TRACE:0x0000001101000000 373ms GEN1:x16:detect
[ 79.416337403,3] PHB#0001[0:1]: TRACE:0x0000102101000000 385ms presence GEN1:x16:polling
[ 79.416435041,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 385ms training GEN1:x01:recovery
[ 79.428714551,3] PHB#0001[0:1]: TRACE:0x0000001011000000 409ms GEN1:x01:detect
[ 79.428848149,3] PHB#0001[0:1]: TRACE:0x0000001101000000 410ms GEN1:x16:detect
[ 79.435142644,3] PHB#0001[0:1]: TRACE:0x0000102101000000 422ms presence GEN1:x16:polling
[ 79.435213255,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 422ms training GEN1:x01:recovery
[ 79.447519172,3] PHB#0001[0:1]: TRACE:0x0000001011000000 446ms GEN1:x01:detect
[ 79.447652909,3] PHB#0001[0:1]: TRACE:0x0000001101000000 446ms GEN1:x16:detect
[ 79.453947298,3] PHB#0001[0:1]: TRACE:0x0000102101000000 459ms presence GEN1:x16:polling
[ 79.454031504,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 459ms training GEN1:x01:recovery
[ 79.466324405,3] PHB#0001[0:1]: TRACE:0x0000001011000000 483ms GEN1:x01:detect
[ 79.466457654,3] PHB#0001[0:1]: TRACE:0x0000001101000000 483ms GEN1:x16:detect
[ 79.472752145,3] PHB#0001[0:1]: TRACE:0x0000102101000000 495ms presence GEN1:x16:polling
[ 79.472842029,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 496ms training GEN1:x01:recovery
[ 79.485128955,3] PHB#0001[0:1]: TRACE:0x0000001011000000 520ms GEN1:x01:detect
[ 79.485262805,3] PHB#0001[0:1]: TRACE:0x0000001101000000 520ms GEN1:x16:detect
[ 79.491557380,3] PHB#0001[0:1]: TRACE:0x0000102101000000 532ms presence GEN1:x16:polling
[ 79.491624209,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 532ms training GEN1:x01:recovery
[ 79.503933819,3] PHB#0001[0:1]: TRACE:0x0000001011000000 556ms GEN1:x01:detect
[ 79.504067273,3] PHB#0001[0:1]: TRACE:0x0000001101000000 557ms GEN1:x16:detect
[ 79.510361913,3] PHB#0001[0:1]: TRACE:0x0000102101000000 569ms presence GEN1:x16:polling
[ 79.510445711,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 569ms training GEN1:x01:recovery
[ 80.010738774,3] PHB#0001[0:1]: TRACE:0x0000001011000000 593ms GEN1:x01:detect
[ 80.010872150,3] PHB#0001[0:1]: TRACE:0x0000001101000000 593ms GEN1:x16:detect
[ 80.017166803,3] PHB#0001[0:1]: TRACE:0x0000102101000000 606ms presence GEN1:x16:polling
[ 80.017262293,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 606ms training GEN1:x01:recovery
[ 80.029543588,3] PHB#0001[0:1]: TRACE:0x0000001011000000 630ms GEN1:x01:detect
[ 80.029677422,3] PHB#0001[0:1]: TRACE:0x0000001101000000 630ms GEN1:x16:detect
[ 80.035971493,3] PHB#0001[0:1]: TRACE:0x0000102101000000 642ms presence GEN1:x16:polling
[ 80.036044443,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 642ms training GEN1:x01:recovery
[ 80.048348595,3] PHB#0001[0:1]: TRACE:0x0000001011000000 666ms GEN1:x01:detect
[ 80.048481867,3] PHB#0001[0:1]: TRACE:0x0000001101000000 667ms GEN1:x16:detect
[ 80.054776361,3] PHB#0001[0:1]: TRACE:0x0000102101000000 679ms presence GEN1:x16:polling
[ 80.054866456,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 679ms training GEN1:x01:recovery
[ 80.067153227,3] PHB#0001[0:1]: TRACE:0x0000001811000000 703ms GEN1:x01:detect
[ 80.067240724,3] PHB#0001[0:1]: TRACE:0x0000001011000000 703ms GEN1:x01:detect
[ 80.067302460,3] PHB#0001[0:1]: TRACE:0x0000001101000000 703ms GEN1:x16:detect
[ 80.073581171,3] PHB#0001[0:1]: TRACE:0x0000102101000000 716ms presence GEN1:x16:polling
[ 80.073659213,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 716ms training GEN1:x01:recovery
[ 80.085958254,3] PHB#0001[0:1]: TRACE:0x0000001011000000 740ms GEN1:x01:detect
[ 80.086091367,3] PHB#0001[0:1]: TRACE:0x0000001101000000 740ms GEN1:x16:detect
[ 80.092386077,3] PHB#0001[0:1]: TRACE:0x0000102101000000 752ms presence GEN1:x16:polling
[ 80.092474778,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 753ms training GEN1:x01:recovery
[ 80.104763045,3] PHB#0001[0:1]: TRACE:0x0000045811000000 777ms GEN1:x01:recovery
[ 80.104843163,3] PHB#0001[0:1]: TRACE:0x0000001011000000 777ms GEN1:x01:detect
[ 80.104897507,3] PHB#0001[0:1]: TRACE:0x0000001101000000 777ms GEN1:x16:detect
[ 80.111190668,3] PHB#0001[0:1]: TRACE:0x0000102101000000 789ms presence GEN1:x16:polling
[ 80.111267596,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 789ms training GEN1:x01:recovery
[ 80.123567996,3] PHB#0001[0:1]: TRACE:0x0000001011000000 813ms GEN1:x01:detect
[ 80.123701224,3] PHB#0001[0:1]: TRACE:0x0000001101000000 814ms GEN1:x16:detect
[ 80.129995878,3] PHB#0001[0:1]: TRACE:0x0000102101000000 826ms presence GEN1:x16:polling
[ 80.130093803,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 826ms training GEN1:x01:recovery
[ 80.142372851,3] PHB#0001[0:1]: TRACE:0x0000001011000000 850ms GEN1:x01:detect
[ 80.142505985,3] PHB#0001[0:1]: TRACE:0x0000001101000000 850ms GEN1:x16:detect
[ 80.148800584,3] PHB#0001[0:1]: TRACE:0x0000102101000000 863ms presence GEN1:x16:polling
[ 80.148889043,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 863ms training GEN1:x01:recovery
[ 80.161177418,3] PHB#0001[0:1]: TRACE:0x0000001011000000 887ms GEN1:x01:detect
[ 80.161311028,3] PHB#0001[0:1]: TRACE:0x0000001101000000 887ms GEN1:x16:detect
[ 80.167605863,3] PHB#0001[0:1]: TRACE:0x0000102101000000 899ms presence GEN1:x16:polling
[ 80.167698650,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 900ms training GEN1:x01:recovery
[ 80.179982633,3] PHB#0001[0:1]: TRACE:0x0000001011000000 924ms GEN1:x01:detect
[ 80.180115703,3] PHB#0001[0:1]: TRACE:0x0000001101000000 924ms GEN1:x16:detect
[ 80.186410299,3] PHB#0001[0:1]: TRACE:0x0000102101000000 936ms presence GEN1:x16:polling
[ 80.186493939,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 936ms training GEN1:x01:recovery
[ 80.198787022,3] PHB#0001[0:1]: TRACE:0x0000001011000000 960ms GEN1:x01:detect
[ 80.198920512,3] PHB#0001[0:1]: TRACE:0x0000001101000000 961ms GEN1:x16:detect
[ 80.205215278,3] PHB#0001[0:1]: TRACE:0x0000102101000000 973ms presence GEN1:x16:polling
[ 80.205309652,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 973ms training GEN1:x01:recovery
[ 80.217591915,3] PHB#0001[0:1]: TRACE:0x0000001011000000 997ms GEN1:x01:detect
[ 80.217725468,3] PHB#0001[0:1]: TRACE:0x0000001101000000 997ms GEN1:x16:detect
[ 80.224019925,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1010ms presence GEN1:x16:polling
[ 80.224096118,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1010ms training GEN1:x01:recovery
[ 80.236396925,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1034ms GEN1:x01:detect
[ 80.236530277,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1034ms GEN1:x16:detect
[ 80.242824819,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1046ms presence GEN1:x16:polling
[ 80.242942230,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1047ms training GEN1:x01:recovery
[ 80.255201670,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1070ms GEN1:x01:detect
[ 80.255335034,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1071ms GEN1:x16:detect
[ 80.261629603,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1083ms presence GEN1:x16:polling
[ 80.261723737,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1083ms training GEN1:x01:recovery
[ 80.274006611,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1107ms GEN1:x01:detect
[ 80.274139906,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1107ms GEN1:x16:detect
[ 80.280434506,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1120ms presence GEN1:x16:polling
[ 80.280513853,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1120ms training GEN1:x01:recovery
[ 80.292811372,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1144ms GEN1:x01:detect
[ 80.292944773,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1144ms GEN1:x16:detect
[ 80.299239309,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1156ms presence GEN1:x16:polling
[ 80.299314302,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1157ms training GEN1:x01:recovery
[ 80.311616234,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1181ms GEN1:x01:detect
[ 80.311749643,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1181ms GEN1:x16:detect
[ 80.318044191,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1193ms presence GEN1:x16:polling
[ 80.318126973,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1193ms training GEN1:x01:recovery
[ 80.330421060,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1217ms GEN1:x01:detect
[ 80.330554499,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1218ms GEN1:x16:detect
[ 80.336849019,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1230ms presence GEN1:x16:polling
[ 80.336955355,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1230ms training GEN1:x01:recovery
[ 80.349225943,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1254ms GEN1:x01:detect
[ 80.349359300,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1254ms GEN1:x16:detect
[ 80.355653805,3] PHB#0001[0:1]: TRACE:0x0000101101000000 1267ms presence GEN1:x16:detect
[ 80.355735014,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1267ms training GEN1:x01:recovery
[ 80.368030733,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1291ms GEN1:x01:detect
[ 80.368164087,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1291ms GEN1:x16:detect
[ 80.374458783,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1303ms presence GEN1:x16:polling
[ 80.374535085,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1304ms training GEN1:x01:recovery
[ 80.386835587,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1328ms GEN1:x01:detect
[ 80.386968955,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1328ms GEN1:x16:detect
[ 80.393263500,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1340ms presence GEN1:x16:polling
[ 80.393348745,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1340ms training GEN1:x01:recovery
[ 80.405640514,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1364ms GEN1:x01:detect
[ 80.405773878,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1365ms GEN1:x16:detect
[ 80.412068333,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1377ms presence GEN1:x16:polling
[ 80.412165862,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1377ms training GEN1:x01:recovery
[ 80.424445284,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1401ms GEN1:x01:detect
[ 80.424578656,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1401ms GEN1:x16:detect
[ 80.430873236,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1414ms presence GEN1:x16:polling
[ 80.430945417,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1414ms training GEN1:x01:recovery
[ 80.443250151,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1438ms GEN1:x01:detect
[ 80.443383476,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1438ms GEN1:x16:detect
[ 80.449678077,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1450ms presence GEN1:x16:polling
[ 80.449763524,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1450ms training GEN1:x01:recovery
[ 80.462054970,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1474ms GEN1:x01:detect
[ 80.462188370,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1475ms GEN1:x16:detect
[ 80.468482883,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1487ms presence GEN1:x16:polling
[ 80.468577713,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1487ms training GEN1:x01:recovery
[ 80.480859813,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1511ms GEN1:x01:detect
[ 80.480993222,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1511ms GEN1:x16:detect
[ 80.487287708,3] PHB#0001[0:1]: TRACE:0x0000101101000000 1524ms presence GEN1:x16:detect
[ 80.487367291,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1524ms training GEN1:x01:recovery
[ 80.499664799,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1548ms GEN1:x01:detect
[ 80.499798030,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1548ms GEN1:x16:detect
[ 80.506092673,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1560ms presence GEN1:x16:polling
[ 80.506169601,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1561ms training GEN1:x01:recovery
[ 81.006469515,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1585ms GEN1:x01:detect
[ 81.006602854,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1585ms GEN1:x16:detect
[ 81.012897428,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1597ms presence GEN1:x16:polling
[ 81.012994856,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1597ms training GEN1:x01:recovery
[ 81.025274387,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1621ms GEN1:x01:detect
[ 81.025407700,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1622ms GEN1:x16:detect
[ 81.031702289,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1634ms presence GEN1:x16:polling
[ 81.031786388,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1634ms training GEN1:x01:recovery
[ 81.044079253,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1658ms GEN1:x01:detect
[ 81.044212650,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1658ms GEN1:x16:detect
[ 81.050507231,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1671ms presence GEN1:x16:polling
[ 81.050592582,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1671ms training GEN1:x01:recovery
[ 81.062884115,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1695ms GEN1:x01:detect
[ 81.063017404,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1695ms GEN1:x16:detect
[ 81.069311931,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1707ms presence GEN1:x16:polling
[ 81.069392671,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1708ms training GEN1:x01:recovery
[ 81.081688850,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1732ms GEN1:x01:detect
[ 81.081822327,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1732ms GEN1:x16:detect
[ 81.088116789,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1744ms presence GEN1:x16:polling
[ 81.088196383,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1744ms training GEN1:x01:recovery
[ 81.100493732,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1768ms GEN1:x01:detect
[ 81.100627158,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1769ms GEN1:x16:detect
[ 81.106921674,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1781ms presence GEN1:x16:polling
[ 81.107002392,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1781ms training GEN1:x01:recovery
[ 81.119298577,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1805ms GEN1:x01:detect
[ 81.119431963,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1805ms GEN1:x16:detect
[ 81.125726470,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1818ms presence GEN1:x16:polling
[ 81.125826782,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1818ms training GEN1:x01:recovery
[ 81.138103431,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1842ms GEN1:x01:detect
[ 81.138236741,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1842ms GEN1:x16:detect
[ 81.144531331,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1854ms presence GEN1:x16:polling
[ 81.144638308,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1855ms training GEN1:x01:recovery
[ 81.156908235,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1878ms GEN1:x01:detect
[ 81.157041613,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1879ms GEN1:x16:detect
[ 81.163336156,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1891ms presence GEN1:x16:polling
[ 81.163424792,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1891ms training GEN1:x01:recovery
[ 81.175713227,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1915ms GEN1:x01:detect
[ 81.175846438,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1915ms GEN1:x16:detect
[ 81.182140996,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1928ms presence GEN1:x16:polling
[ 81.182217447,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1928ms training GEN1:x01:recovery
[ 81.194517902,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1952ms GEN1:x01:detect
[ 81.194651337,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1952ms GEN1:x16:detect
[ 81.200945881,3] PHB#0001[0:1]: TRACE:0x0000102101000000 1965ms presence GEN1:x16:polling
[ 81.201022752,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 1965ms training GEN1:x01:recovery
[ 81.213322798,3] PHB#0001[0:1]: TRACE:0x0000001011000000 1989ms GEN1:x01:detect
[ 81.213456113,3] PHB#0001[0:1]: TRACE:0x0000001101000000 1989ms GEN1:x16:detect
[ 81.219750696,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2001ms presence GEN1:x16:polling
[ 81.219834500,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2001ms training GEN1:x01:recovery
[ 81.232127661,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2025ms GEN1:x01:detect
[ 81.232261018,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2026ms GEN1:x16:detect
[ 81.238555516,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2038ms presence GEN1:x16:polling
[ 81.238639787,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2038ms training GEN1:x01:recovery
[ 81.250932485,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2062ms GEN1:x01:detect
[ 81.251065819,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2062ms GEN1:x16:detect
[ 81.257360370,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2075ms presence GEN1:x16:polling
[ 81.257442789,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2075ms training GEN1:x01:recovery
[ 81.269737308,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2099ms GEN1:x01:detect
[ 81.269870705,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2099ms GEN1:x16:detect
[ 81.276165260,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2111ms presence GEN1:x16:polling
[ 81.276250440,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2112ms training GEN1:x01:recovery
[ 81.288542135,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2136ms GEN1:x01:detect
[ 81.288675519,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2136ms GEN1:x16:detect
[ 81.294970055,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2148ms presence GEN1:x16:polling
[ 81.295058326,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2148ms training GEN1:x01:recovery
[ 81.307347052,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2172ms GEN1:x01:detect
[ 81.307480347,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2173ms GEN1:x16:detect
[ 81.313774923,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2185ms presence GEN1:x16:polling
[ 81.313858572,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2185ms training GEN1:x01:recovery
[ 81.326151827,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2209ms GEN1:x01:detect
[ 81.326285243,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2209ms GEN1:x16:detect
[ 81.332579811,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2222ms presence GEN1:x16:polling
[ 81.332658950,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2222ms training GEN1:x01:recovery
[ 81.344956669,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2246ms GEN1:x01:detect
[ 81.345090039,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2246ms GEN1:x16:detect
[ 81.351384689,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2258ms presence GEN1:x16:polling
[ 81.351471973,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2258ms training GEN1:x01:recovery
[ 81.363761546,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2282ms GEN1:x01:detect
[ 81.363894907,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2283ms GEN1:x16:detect
[ 81.370189431,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2295ms presence GEN1:x16:polling
[ 81.370282818,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2295ms training GEN1:x01:recovery
[ 81.382566347,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2319ms GEN1:x01:detect
[ 81.382699787,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2319ms GEN1:x16:detect
[ 81.388994299,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2332ms presence GEN1:x16:polling
[ 81.389079739,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2332ms training GEN1:x01:recovery
[ 81.401371240,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2356ms GEN1:x01:detect
[ 81.401504614,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2356ms GEN1:x16:detect
[ 81.407799148,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2369ms presence GEN1:x16:polling
[ 81.407881802,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2369ms training GEN1:x01:recovery
[ 81.420176052,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2393ms GEN1:x01:detect
[ 81.420309415,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2393ms GEN1:x16:detect
[ 81.426604008,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2405ms presence GEN1:x16:polling
[ 81.426692167,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2405ms training GEN1:x01:recovery
[ 81.438980907,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2429ms GEN1:x01:detect
[ 81.439114245,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2430ms GEN1:x16:detect
[ 81.445408889,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2442ms presence GEN1:x16:polling
[ 81.445492403,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2442ms training GEN1:x01:recovery
[ 81.457785729,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2466ms GEN1:x01:detect
[ 81.457919117,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2466ms GEN1:x16:detect
[ 81.464213735,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2479ms presence GEN1:x16:polling
[ 81.464309496,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2479ms training GEN1:x01:recovery
[ 81.476590619,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2503ms GEN1:x01:detect
[ 81.476724002,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2503ms GEN1:x16:detect
[ 81.483018552,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2515ms presence GEN1:x16:polling
[ 81.483096454,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2516ms training GEN1:x01:recovery
[ 81.495395432,3] PHB#0001[0:1]: TRACE:0x0000001811000000 2540ms GEN1:x01:detect
[ 81.495483588,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2540ms GEN1:x01:detect
[ 81.495541948,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2540ms GEN1:x16:detect
[ 81.501823365,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2552ms presence GEN1:x16:polling
[ 81.501910302,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2552ms training GEN1:x01:recovery
[ 82.002200362,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2576ms GEN1:x01:detect
[ 82.002333701,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2577ms GEN1:x16:detect
[ 82.008628201,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2589ms presence GEN1:x16:polling
[ 82.008709260,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2589ms training GEN1:x01:recovery
[ 82.021005084,3] PHB#0001[0:1]: TRACE:0x0000001811000000 2613ms GEN1:x01:detect
[ 82.021087735,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2613ms GEN1:x01:detect
[ 82.021142377,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2613ms GEN1:x16:detect
[ 82.027433075,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2626ms presence GEN1:x16:polling
[ 82.027535349,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2626ms training GEN1:x01:recovery
[ 82.039809991,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2650ms GEN1:x01:detect
[ 82.039943293,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2650ms GEN1:x16:detect
[ 82.046238007,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2662ms presence GEN1:x16:polling
[ 82.046307132,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2662ms training GEN1:x01:recovery
[ 82.058614837,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2687ms GEN1:x01:detect
[ 82.058748241,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2687ms GEN1:x16:detect
[ 82.065042710,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2699ms presence GEN1:x16:polling
[ 82.065122611,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2699ms training GEN1:x01:recovery
[ 82.077419665,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2723ms GEN1:x01:detect
[ 82.077553099,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2723ms GEN1:x16:detect
[ 82.083847527,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2736ms presence GEN1:x16:polling
[ 82.083927096,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2736ms training GEN1:x01:recovery
[ 82.096224500,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2760ms GEN1:x01:detect
[ 82.096357857,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2760ms GEN1:x16:detect
[ 82.102652518,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2773ms presence GEN1:x16:polling
[ 82.102739421,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2773ms training GEN1:x01:recovery
[ 82.115029380,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2797ms GEN1:x01:detect
[ 82.115162712,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2797ms GEN1:x16:detect
[ 82.121457287,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2809ms presence GEN1:x16:polling
[ 82.121540314,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2809ms training GEN1:x01:recovery
[ 82.133834215,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2833ms GEN1:x01:detect
[ 82.133967652,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2834ms GEN1:x16:detect
[ 82.140262169,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2846ms presence GEN1:x16:polling
[ 82.140343143,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2846ms training GEN1:x01:recovery
[ 82.152639184,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2870ms GEN1:x01:detect
[ 82.152772433,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2870ms GEN1:x16:detect
[ 82.159067014,3] PHB#0001[0:1]: TRACE:0x0000101101000000 2883ms presence GEN1:x16:detect
[ 82.159143212,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2883ms training GEN1:x01:recovery
[ 82.171443858,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2907ms GEN1:x01:detect
[ 82.171577315,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2907ms GEN1:x16:detect
[ 82.177871779,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2919ms presence GEN1:x16:polling
[ 82.177958573,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2920ms training GEN1:x01:recovery
[ 82.190248717,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2944ms GEN1:x01:detect
[ 82.190382082,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2944ms GEN1:x16:detect
[ 82.196676669,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2956ms presence GEN1:x16:polling
[ 82.196756543,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2956ms training GEN1:x01:recovery
[ 82.209053544,3] PHB#0001[0:1]: TRACE:0x0000001011000000 2980ms GEN1:x01:detect
[ 82.209186896,3] PHB#0001[0:1]: TRACE:0x0000001101000000 2981ms GEN1:x16:detect
[ 82.215481495,3] PHB#0001[0:1]: TRACE:0x0000102101000000 2993ms presence GEN1:x16:polling
[ 82.215557834,3] PHB#0001[0:1]: TRACE:0x00001c5811000000 2993ms training GEN1:x01:recovery
[ 82.218862026,3] PHB#0001[0:1]: TRACE: Timeout waiting for link up.
[ 82.219433985,7] PHB#0001[0:1]: LINK: Start polling
[ 82.219953604,7] PHB#0001[0:1]: LINK: Electrical link detected
[ 84.221864624,3] PHB#0001[0:1]: LINK: Timeout waiting for link up
[ 84.221930533,7] PHB#0001[0:1]: LINK: DLP train control: 0x0000001101000000
[ 84.221931850,3] PHB#0001[0:1]: Electrical link detected but won't train
[ 84.221970100,7] PHB#0001:00:00.0 Error -6 resetting
[ 84.222837386,7] PHB#0001:00:00.0 Link down
[ 84.222846966,7] PHB#0001:00:00.0 Scanning (upstream only)...
[ 84.222879762,7] PHB#0001:00:00.0 Found VID:1014 DEV:04c1 TYP:4 MF- BR+ EX+
[ 84.279369573,5] PHB#0001:00:00.0 [ROOT] 1014 04c1 R:00 C:060400 B:00..00
[ 86.173842996,7] PHB#0001[0:1]: Purging all IODA tables...
[ 338.488829106,7] PHB#0001[0:1]: Purging all IODA tables...
[ 340.284070969,7] PHB#0001[0:1]: Purging all IODA tables...
@mikey For what it's worth, Romulus has all of the PCIe presence detect pins tied together. Talos II retains this unusual feature. How is skiboot supposed to detect the actual electrical width of the card?
@mikey It's a little more complex than that because the vendor only brought out one lane of the four on the PI7C9X130. This is normally supported by the PCIe standards but may not have seen much testing on POWER as it isn't common.
also try
nvram -p ibm,skiboot --update-config pcie-max-link-speed=1
Note: that will limit all PHBs to gen1... but at least it might train
The link speed and link width status fields is a little deceiving because those status bits are only valid for certain state machines in the LTSSM. However, i do see that during polling we see a x16 link width which could be valid if the LTSSM is in Polling.Compliance. The only way that could happen is if we detected a receiver detect on all 16 lanes, entered Polling, and timed out after 24ms and entered Polling.Compliance. We have observed with the latest p9 pci pll settings, there could be a failure for the P9 RX to detect an exit of Electrical Idle if there are unused lanes showing a receiver detect when there shouldn't be any. So if this is truly a x1 interface, what are the other 15 unused lanes doing? Can someone confirm how many lanes are actually connected. Would help determine if we are seeing this issue we have observed on a different setup I am debugging.
@ricmata I can confirm only 1 lane is electrically connected. There is no edge connector and no electrical contacts in the rest of the slot; i.e. the remainder of the slot is physically empty.
Can you check if you have the following configs are enabled in your kernel.
You should see this if everything is good. $ grep SCOM /boot/config-uname -r CONFIG_PPC_SCOM=y CONFIG_SCOM_DEBUGFS=y
@ricmata Yeah, we have access to a debug system with those options enabled.
What would you like to see?
From memory this was fixed by falling back to training at Gen1 speeds. Can we close this @madscientist159?
@oohal I was the author of the Gen 1 patch :wink: My understanding is that it only fixed some cards (DTV ATSC tuners, specifically), and that the PCIe bridge that this report is about does not work yet?