search

open-power / skiboot

OPAL boot and runtime firmware for POWER
Apache License 2.0
98 stars 134 forks source link

PCIe to PCI bridge will not train #176

Open madscientist159 opened 6 years ago

madscientist159 commented 6 years ago

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?

mikey commented 6 years ago

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

madscientist159 commented 6 years ago

@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...
madscientist159 commented 6 years ago

@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 commented 6 years ago

@ricmata can you take a look at the trace? It's a gen1 x4 card

madscientist159 commented 6 years ago

@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.

mikey commented 6 years ago

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

madscientist159 commented 6 years ago

No effect. Debugging live in other channel ATM.

ricmata commented 6 years ago

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.

madscientist159 commented 6 years ago

@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.

ricmata commented 6 years ago

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

madscientist159 commented 6 years ago

@ricmata Yeah, we have access to a debug system with those options enabled.

What would you like to see?

oohal commented 5 years ago

From memory this was fixed by falling back to training at Gen1 speeds. Can we close this @madscientist159?

madscientist159 commented 5 years ago

@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?