soak-main-thread-stall.txt (9038B)
1 1403.862422 [hmd] serial REDACTED-DEVICE-SERIAL 2 1404.123422 [hmd] wake commands accepted; waiting for IMU and video evidence 3 1404.123422 waiting for the headset display... 4 1404.720422 [hmd] display ready=false; raw 05 00 01 01 00 00 00 00 00 00 00 5 1404.720422 headset reports display not ready 6 1406.124422 recovery: display absent after grace period; resetting DP adapter 7 rid 1 recover DRST: 0x00000000, task result: 0x00, bytes: 64 8 1406.292422 recovery helper accepted DRST; waiting for display ADD 9 1407.503422 [hmd] display ready=false; raw 05 00 01 01 00 00 00 00 00 00 00 10 1407.503422 headset reports display not ready 11 1408.481422 [display callback] id=2 flags=4378 main=true 12 1408.482422 [display callback] handled after 0.7 ms 13 1408.490422 captured display 2: ok 14 1409.160422 [display callback] id=2 flags=8 main=true 15 1409.180422 mode 4320x2160@90 (ui 4320x2160): ok 16 1409.186422 headset display link: maximum 90 fps 17 1409.186422 rendering on headset (4320.0, 2160.0), drawable (4320.0, 2160.0) 18 1409.187422 headset display link: maximum 90 fps 19 1409.187422 rendering on headset (4320.0, 2160.0), drawable (4320.0, 2160.0) 20 1409.188422 [display callback] handled after 28.0 ms 21 1409.188422 headset display link: maximum 90 fps 22 1409.188422 rendering on headset (4320.0, 2160.0), drawable (4320.0, 2160.0) 23 frame 1 drawable (4320.0, 2160.0) 24 frame 300 drawable (4320.0, 2160.0) 25 1413.405422 [hmd] display ready=false; raw 05 00 01 01 00 59 00 00 00 07 3b 26 1413.405422 headset reports display not ready 27 1414.450422 drawable acquisition stalled 1001.5 ms 28 1414.452422 health display=2 submitted=385 presented=377 skipped=6 fps=34.36 presentAge=1.03s GPUms=1.54 GPUerrors=0 IMU=9052 lastIMU=0.0s status=3 29 1424.457422 [display callback] id=2 flags=4922 main=true 30 1424.459422 [display callback] handled after 1.7 ms 31 1424.473422 headset display LOST 32 1424.473422 headset display back after 14 ms 33 1424.480422 captured display 2: ok 34 1425.023422 [display callback] id=2 flags=8 main=true 35 1425.039422 mode 4320x2160@90 (ui 4320x2160): ok 36 1425.040422 headset display link: maximum 90 fps 37 1425.040422 rendering on headset (4320.0, 2160.0), drawable (4320.0, 2160.0) 38 1425.040422 headset display link: maximum 90 fps 39 1425.040422 rendering on headset (4320.0, 2160.0), drawable (4320.0, 2160.0) 40 1425.041422 [display callback] handled after 17.8 ms 41 1425.041422 headset display link: maximum 90 fps 42 1425.041422 rendering on headset (4320.0, 2160.0), drawable (4320.0, 2160.0) 43 1425.042422 health display=2 submitted=385 presented=377 skipped=8 fps=0.00 presentAge=11.62s GPUms=1.54 GPUerrors=0 IMU=9212 lastIMU=0.0s status=3 44 1425.043422 [hmd] display ready=true; raw 05 01 01 01 01 59 00 00 00 07 3b 45 1425.043422 [hmd] display ready=false; raw 05 00 01 01 00 3b 00 00 00 0a 27 46 1425.043422 headset reports display not ready 47 frame 600 drawable (4320.0, 2160.0) 48 1430.735422 [hmd] display ready=false; raw 05 00 01 01 00 3b 00 00 00 0a 27 49 1430.735422 headset reports display not ready 50 frame 900 drawable (4320.0, 2160.0) 51 1431.710422 drawable acquisition stalled 932.4 ms 52 1433.203422 [hmd] display ready=true; raw 05 01 01 01 01 3b 00 00 00 0a 27 53 1433.311422 [display callback] id=2 flags=4922 main=true 54 1433.313422 [display callback] handled after 2.3 ms 55 1433.314422 headset display LOST 56 1433.314422 headset display back after 1 ms 57 1433.317422 captured display 2: ok 58 1433.860422 [display callback] id=2 flags=8 main=true 59 1433.870422 mode 4320x2160@90 (ui 4320x2160): ok 60 1433.871422 headset display link: maximum 90 fps 61 1433.871422 rendering on headset (4320.0, 2160.0), drawable (4320.0, 2160.0) 62 1433.872422 headset display link: maximum 90 fps 63 1433.872422 rendering on headset (4320.0, 2160.0), drawable (4320.0, 2160.0) 64 1433.873422 [display callback] handled after 12.5 ms 65 1433.873422 headset display link: maximum 90 fps 66 1433.873422 rendering on headset (4320.0, 2160.0), drawable (4320.0, 2160.0) 67 1433.877422 [hmd] display ready=false; raw 05 00 01 01 00 3b 00 00 00 0a 27 68 1433.877422 headset reports display not ready 69 1434.124422 health display=2 submitted=1019 presented=1000 skipped=16 fps=68.60 presentAge=0.01s GPUms=1.72 GPUerrors=0 IMU=17096 lastIMU=0.0s status=8 70 1435.201422 [hmd] display ready=true; raw 05 01 01 01 01 3b 00 00 00 0a 27 71 frame 1200 drawable (4320.0, 2160.0) 72 frame 1500 drawable (4320.0, 2160.0) 73 frame 1800 drawable (4320.0, 2160.0) 74 1444.124422 health display=2 submitted=1919 presented=1900 skipped=16 fps=90.00 presentAge=0.01s GPUms=1.78 GPUerrors=0 IMU=27100 lastIMU=0.0s status=9 75 frame 2100 drawable (4320.0, 2160.0) 76 frame 2400 drawable (4320.0, 2160.0) 77 frame 2700 drawable (4320.0, 2160.0) 78 1454.124422 health display=2 submitted=2819 presented=2800 skipped=16 fps=90.00 presentAge=0.01s GPUms=1.82 GPUerrors=0 IMU=37104 lastIMU=0.0s status=9 79 frame 3000 drawable (4320.0, 2160.0) 80 frame 3300 drawable (4320.0, 2160.0) 81 frame 3600 drawable (4320.0, 2160.0) 82 1464.124422 health display=2 submitted=3719 presented=3700 skipped=16 fps=90.00 presentAge=0.01s GPUms=1.83 GPUerrors=0 IMU=47108 lastIMU=0.0s status=9 83 frame 3900 drawable (4320.0, 2160.0) 84 frame 4200 drawable (4320.0, 2160.0) 85 frame 4500 drawable (4320.0, 2160.0) 86 1474.124422 health display=2 submitted=4619 presented=4600 skipped=16 fps=90.00 presentAge=0.01s GPUms=1.84 GPUerrors=0 IMU=57116 lastIMU=0.0s status=9 87 frame 4800 drawable (4320.0, 2160.0) 88 frame 5100 drawable (4320.0, 2160.0) 89 frame 5400 drawable (4320.0, 2160.0) 90 1484.123422 health display=2 submitted=5519 presented=5499 skipped=16 fps=89.90 presentAge=0.01s GPUms=1.84 GPUerrors=0 IMU=67120 lastIMU=0.0s status=9 91 frame 5700 drawable (4320.0, 2160.0) 92 frame 6000 drawable (4320.0, 2160.0) 93 frame 6300 drawable (4320.0, 2160.0) 94 1494.123422 health display=2 submitted=6419 presented=6399 skipped=16 fps=90.00 presentAge=0.01s GPUms=1.85 GPUerrors=0 IMU=77124 lastIMU=0.0s status=9 95 frame 6600 drawable (4320.0, 2160.0) 96 frame 6900 drawable (4320.0, 2160.0) 97 frame 7200 drawable (4320.0, 2160.0) 98 1504.123422 health display=2 submitted=7319 presented=7299 skipped=16 fps=90.00 presentAge=0.01s GPUms=1.85 GPUerrors=0 IMU=87128 lastIMU=0.0s status=9 99 frame 7500 drawable (4320.0, 2160.0) 100 frame 7800 drawable (4320.0, 2160.0) 101 frame 8100 drawable (4320.0, 2160.0) 102 1514.123422 health display=2 submitted=8219 presented=8199 skipped=16 fps=90.00 presentAge=0.01s GPUms=1.85 GPUerrors=0 IMU=97132 lastIMU=0.0s status=9 103 frame 8400 drawable (4320.0, 2160.0) 104 frame 8700 drawable (4320.0, 2160.0) 105 frame 9000 drawable (4320.0, 2160.0) 106 1524.122422 health display=2 submitted=9119 presented=9099 skipped=16 fps=90.01 presentAge=0.00s GPUms=1.85 GPUerrors=0 IMU=107136 lastIMU=0.0s status=9 107 frame 9300 drawable (4320.0, 2160.0) 108 frame 9600 drawable (4320.0, 2160.0) 109 frame 9900 drawable (4320.0, 2160.0) 110 1534.123422 health display=2 submitted=10019 presented=9999 skipped=16 fps=89.99 presentAge=0.01s GPUms=1.85 GPUerrors=0 IMU=117140 lastIMU=0.0s status=9 111 frame 10200 drawable (4320.0, 2160.0) 112 frame 10500 drawable (4320.0, 2160.0) 113 frame 10800 drawable (4320.0, 2160.0) 114 1544.123422 health display=2 submitted=10919 presented=10899 skipped=16 fps=90.00 presentAge=0.00s GPUms=1.85 GPUerrors=0 IMU=127144 lastIMU=0.0s status=9 115 frame 11100 drawable (4320.0, 2160.0) 116 frame 11400 drawable (4320.0, 2160.0) 117 frame 11700 drawable (4320.0, 2160.0) 118 1554.123422 health display=2 submitted=11819 presented=11799 skipped=16 fps=90.00 presentAge=0.00s GPUms=1.85 GPUerrors=0 IMU=137148 lastIMU=0.0s status=9 119 frame 12000 drawable (4320.0, 2160.0) 120 frame 12300 drawable (4320.0, 2160.0) 121 frame 12600 drawable (4320.0, 2160.0) 122 1564.122422 health display=2 submitted=12719 presented=12699 skipped=16 fps=90.00 presentAge=0.00s GPUms=1.85 GPUerrors=0 IMU=147152 lastIMU=0.0s status=9 123 frame 12900 drawable (4320.0, 2160.0) 124 frame 13200 drawable (4320.0, 2160.0) 125 frame 13500 drawable (4320.0, 2160.0) 126 1574.122422 health display=2 submitted=13619 presented=13599 skipped=16 fps=90.00 presentAge=0.00s GPUms=1.85 GPUerrors=0 IMU=157160 lastIMU=0.0s status=9 127 frame 13800 drawable (4320.0, 2160.0) 128 frame 14100 drawable (4320.0, 2160.0) 129 frame 14400 drawable (4320.0, 2160.0) 130 1584.122422 health display=2 submitted=14519 presented=14499 skipped=16 fps=90.00 presentAge=0.00s GPUms=1.85 GPUerrors=0 IMU=167164 lastIMU=0.0s status=9 131 frame 14700 drawable (4320.0, 2160.0) 132 frame 15000 drawable (4320.0, 2160.0) 133 frame 15300 drawable (4320.0, 2160.0) 134 1594.122422 health display=2 submitted=15419 presented=15399 skipped=16 fps=90.00 presentAge=0.00s GPUms=1.85 GPUerrors=0 IMU=177168 lastIMU=0.0s status=9 135 frame 15600 drawable (4320.0, 2160.0) 136 frame 15900 drawable (4320.0, 2160.0) 137 frame 16200 drawable (4320.0, 2160.0) 138 1604.121422 health display=2 submitted=16319 presented=16299 skipped=16 fps=90.00 presentAge=0.00s GPUms=1.85 GPUerrors=0 IMU=187172 lastIMU=0.0s status=9 139 frame 16500 drawable (4320.0, 2160.0) 140 frame 16800 drawable (4320.0, 2160.0) 141 frame 17100 drawable (4320.0, 2160.0) 142 1614.122422 health display=2 submitted=17219 presented=17199 skipped=16 fps=90.00 presentAge=0.00s GPUms=1.85 GPUerrors=0 IMU=197176 lastIMU=0.0s status=9 143 frame 17400 drawable (4320.0, 2160.0)