Results for igt@kms_flip@plain-flip-fb-recreate@a-vga1

Machine description: shard-snb1

Result: Fail

git-log-oneline i915_display_info9 igt_runner9 runtimes9 results9.json i915_display_info_post_exec9 boot9 dmesg9

DetailValue
Duration 6.31 seconds
Hostname 
shard-snb1
Igt-Version 
IGT-Version: 1.30-g8bce68b09 (x86_64) (Linux: 6.13.0-rc3-CI_DRM_15862-g1d0ef0c9b38e+ x86_64)
Out 
Using IGT_SRANDOM=1734544878 for randomisation
Opened device: /dev/dri/card0
Using monotonic timestamps
Starting subtest: plain-flip-fb-recreate
Starting dynamic subtest: A-VGA1
  1024x768: 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa 
Expected frametime: 16666us; measured 17022.8us +- 4.608us accuracy 0.08%
vblank interval differs from modeline! expected 16665.6us, measured 17023us +- 4.608us, difference 357.2us (77.5 sigma)
Stack trace:
  #0 ../lib/igt_core.c:2051 __igt_fail_assert()
  #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1845 run_test()
  #3 ../tests/kms_flip.c:2078 __igt_unique____real_main2001()
  #4 ../tests/kms_flip.c:2001 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest A-VGA1: FAIL (6.314s)
Err 
Starting subtest: plain-flip-fb-recreate
Starting dynamic subtest: A-VGA1
(kms_flip:2632) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:2632) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
Dynamic subtest A-VGA1 failed.
**** DEBUG ****
(kms_flip:2632) DEBUG: flip ts/seq: last 155.660187/1383, current 155.677216/1384: elapsed=17022.0us expected=17022.8us +- 85.1us, error 0.0%
(kms_flip:2632) DEBUG: name = flip
last_ts = 155.677216
last_received_ts = 155.676666
last_seq = 1384
current_ts = 155.694244
current_received_ts = 155.693649
current_seq = 1385
count = 37
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 155.677216/1384, current 155.694244/1385: elapsed=17029.0us expected=17022.8us +- 85.1us, error 0.0%
(kms_flip:2632) DEBUG: name = flip
last_ts = 155.694244
last_received_ts = 155.693649
last_seq = 1385
current_ts = 155.711243
current_received_ts = 155.710693
current_seq = 1386
count = 38
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 155.694244/1385, current 155.711243/1386: elapsed=17004.0us expected=17022.8us +- 85.1us, error 0.1%
(kms_flip:2632) DEBUG: name = flip
last_ts = 155.711243
last_received_ts = 155.710693
last_seq = 1386
current_ts = 155.728226
current_received_ts = 155.727676
current_seq = 1387
count = 39
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 155.711243/1386, current 155.728226/1387: elapsed=16986.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 155.728226
last_received_ts = 155.727676
last_seq = 1387
current_ts = 155.745209
current_received_ts = 155.744659
current_seq = 1388
count = 40
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 155.728226/1387, current 155.745209/1388: elapsed=16980.0us expected=17022.8us +- 85.1us, error 0.3%
(kms_flip:2632) DEBUG: name = flip
last_ts = 155.745209
last_received_ts = 155.744659
last_seq = 1388
current_ts = 155.762207
current_received_ts = 155.761642
current_seq = 1389
count = 41
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 155.745209/1388, current 155.762207/1389: elapsed=16991.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 155.762207
last_received_ts = 155.761642
last_seq = 1389
current_ts = 155.779190
current_received_ts = 155.778641
current_seq = 1390
count = 42
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 155.762207/1389, current 155.779190/1390: elapsed=16994.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 155.779190
last_received_ts = 155.778641
last_seq = 1390
current_ts = 155.796158
current_received_ts = 155.795609
current_seq = 1391
count = 43
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 155.779190/1390, current 155.796158/1391: elapsed=16970.0us expected=17022.8us +- 85.1us, error 0.3%
(kms_flip:2632) DEBUG: name = flip
last_ts = 155.796158
last_received_ts = 155.795609
last_seq = 1391
current_ts = 155.813156
current_received_ts = 155.812561
current_seq = 1392
count = 44
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 155.796158/1391, current 155.813156/1392: elapsed=16990.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 155.813156
last_received_ts = 155.812561
last_seq = 1392
current_ts = 155.830139
current_received_ts = 155.829575
current_seq = 1393
count = 45
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 155.813156/1392, current 155.830139/1393: elapsed=16980.0us expected=17022.8us +- 85.1us, error 0.3%
(kms_flip:2632) DEBUG: name = flip
last_ts = 155.830139
last_received_ts = 155.829575
last_seq = 1393
current_ts = 155.847122
current_received_ts = 155.846542
current_seq = 1394
count = 46
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 155.830139/1393, current 155.847122/1394: elapsed=16987.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 155.847122
last_received_ts = 155.846542
last_seq = 1394
current_ts = 155.864105
current_received_ts = 155.863556
current_seq = 1395
count = 47
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 155.847122/1394, current 155.864105/1395: elapsed=16987.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 155.864105
last_received_ts = 155.863556
last_seq = 1395
current_ts = 155.881104
current_received_ts = 155.880524
current_seq = 1396
count = 48
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 155.864105/1395, current 155.881104/1396: elapsed=16995.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 155.881104
last_received_ts = 155.880524
last_seq = 1396
current_ts = 155.898087
current_received_ts = 155.897522
current_seq = 1397
count = 49
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 155.881104/1396, current 155.898087/1397: elapsed=16976.0us expected=17022.8us +- 85.1us, error 0.3%
(kms_flip:2632) DEBUG: name = flip
last_ts = 155.898087
last_received_ts = 155.897522
last_seq = 1397
current_ts = 155.915070
current_received_ts = 155.914536
current_seq = 1398
count = 50
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 155.898087/1397, current 155.915070/1398: elapsed=16991.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 155.915070
last_received_ts = 155.914536
last_seq = 1398
current_ts = 155.932053
current_received_ts = 155.931473
current_seq = 1399
count = 51
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 155.915070/1398, current 155.932053/1399: elapsed=16987.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 155.932053
last_received_ts = 155.931473
last_seq = 1399
current_ts = 155.949036
current_received_ts = 155.948502
current_seq = 1400
count = 52
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 155.932053/1399, current 155.949036/1400: elapsed=16986.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 155.949036
last_received_ts = 155.948502
last_seq = 1400
current_ts = 155.966034
current_received_ts = 155.965485
current_seq = 1401
count = 53
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 155.949036/1400, current 155.966034/1401: elapsed=16984.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 155.966034
last_received_ts = 155.965485
last_seq = 1401
current_ts = 155.983017
current_received_ts = 155.982452
current_seq = 1402
count = 54
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 155.966034/1401, current 155.983017/1402: elapsed=16996.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 155.983017
last_received_ts = 155.982452
last_seq = 1402
current_ts = 156.000000
current_received_ts = 155.999435
current_seq = 1403
count = 55
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 155.983017/1402, current 156.000000/1403: elapsed=16971.0us expected=17022.8us +- 85.1us, error 0.3%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.000000
last_received_ts = 155.999435
last_seq = 1403
current_ts = 156.016983
current_received_ts = 156.016434
current_seq = 1404
count = 56
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.000000/1403, current 156.016983/1404: elapsed=16991.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.016983
last_received_ts = 156.016434
last_seq = 1404
current_ts = 156.033981
current_received_ts = 156.033371
current_seq = 1405
count = 57
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.016983/1404, current 156.033981/1405: elapsed=16996.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.033981
last_received_ts = 156.033371
last_seq = 1405
current_ts = 156.050949
current_received_ts = 156.050385
current_seq = 1406
count = 58
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.033981/1405, current 156.050949/1406: elapsed=16974.0us expected=17022.8us +- 85.1us, error 0.3%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.050949
last_received_ts = 156.050385
last_seq = 1406
current_ts = 156.067947
current_received_ts = 156.067398
current_seq = 1407
count = 59
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.050949/1406, current 156.067947/1407: elapsed=16987.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.067947
last_received_ts = 156.067398
last_seq = 1407
current_ts = 156.084930
current_received_ts = 156.084381
current_seq = 1408
count = 60
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.067947/1407, current 156.084930/1408: elapsed=16985.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.084930
last_received_ts = 156.084381
last_seq = 1408
current_ts = 156.101913
current_received_ts = 156.101379
current_seq = 1409
count = 61
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.084930/1408, current 156.101913/1409: elapsed=16986.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.101913
last_received_ts = 156.101379
last_seq = 1409
current_ts = 156.118912
current_received_ts = 156.118347
current_seq = 1410
count = 62
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.101913/1409, current 156.118912/1410: elapsed=16994.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.118912
last_received_ts = 156.118347
last_seq = 1410
current_ts = 156.135880
current_received_ts = 156.135330
current_seq = 1411
count = 63
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.118912/1410, current 156.135880/1411: elapsed=16977.0us expected=17022.8us +- 85.1us, error 0.3%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.135880
last_received_ts = 156.135330
last_seq = 1411
current_ts = 156.152863
current_received_ts = 156.152222
current_seq = 1412
count = 64
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.135880/1411, current 156.152863/1412: elapsed=16977.0us expected=17022.8us +- 85.1us, error 0.3%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.152863
last_received_ts = 156.152222
last_seq = 1412
current_ts = 156.169861
current_received_ts = 156.169281
current_seq = 1413
count = 65
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.152863/1412, current 156.169861/1413: elapsed=16994.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.169861
last_received_ts = 156.169281
last_seq = 1413
current_ts = 156.186844
current_received_ts = 156.186218
current_seq = 1414
count = 66
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.169861/1413, current 156.186844/1414: elapsed=16992.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.186844
last_received_ts = 156.186218
last_seq = 1414
current_ts = 156.203842
current_received_ts = 156.203674
current_seq = 1415
count = 67
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.186844/1414, current 156.203842/1415: elapsed=16991.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.203842
last_received_ts = 156.203674
last_seq = 1415
current_ts = 156.220810
current_received_ts = 156.220169
current_seq = 1416
count = 68
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.203842/1415, current 156.220810/1416: elapsed=16969.0us expected=17022.8us +- 85.1us, error 0.3%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.220810
last_received_ts = 156.220169
last_seq = 1416
current_ts = 156.237793
current_received_ts = 156.237198
current_seq = 1417
count = 69
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.220810/1416, current 156.237793/1417: elapsed=16991.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.237793
last_received_ts = 156.237198
last_seq = 1417
current_ts = 156.254791
current_received_ts = 156.254181
current_seq = 1418
count = 70
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.237793/1417, current 156.254791/1418: elapsed=16993.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.254791
last_received_ts = 156.254181
last_seq = 1418
current_ts = 156.271759
current_received_ts = 156.271133
current_seq = 1419
count = 71
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.254791/1418, current 156.271759/1419: elapsed=16970.0us expected=17022.8us +- 85.1us, error 0.3%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.271759
last_received_ts = 156.271133
last_seq = 1419
current_ts = 156.288757
current_received_ts = 156.288162
current_seq = 1420
count = 72
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.271759/1419, current 156.288757/1420: elapsed=16994.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.288757
last_received_ts = 156.288162
last_seq = 1420
current_ts = 156.305740
current_received_ts = 156.305145
current_seq = 1421
count = 73
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.288757/1420, current 156.305740/1421: elapsed=16984.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.305740
last_received_ts = 156.305145
last_seq = 1421
current_ts = 156.322739
current_received_ts = 156.322128
current_seq = 1422
count = 74
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.305740/1421, current 156.322739/1422: elapsed=16996.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.322739
last_received_ts = 156.322128
last_seq = 1422
current_ts = 156.339706
current_received_ts = 156.339096
current_seq = 1423
count = 75
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.322739/1422, current 156.339706/1423: elapsed=16973.0us expected=17022.8us +- 85.1us, error 0.3%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.339706
last_received_ts = 156.339096
last_seq = 1423
current_ts = 156.356705
current_received_ts = 156.356476
current_seq = 1424
count = 76
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.339706/1423, current 156.356705/1424: elapsed=16990.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.356705
last_received_ts = 156.356476
last_seq = 1424
current_ts = 156.373672
current_received_ts = 156.373093
current_seq = 1425
count = 77
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.356705/1424, current 156.373672/1425: elapsed=16981.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.373672
last_received_ts = 156.373093
last_seq = 1425
current_ts = 156.390671
current_received_ts = 156.390060
current_seq = 1426
count = 78
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.373672/1425, current 156.390671/1426: elapsed=16986.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.390671
last_received_ts = 156.390060
last_seq = 1426
current_ts = 156.407654
current_received_ts = 156.407074
current_seq = 1427
count = 79
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.390671/1426, current 156.407654/1427: elapsed=16985.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.407654
last_received_ts = 156.407074
last_seq = 1427
current_ts = 156.424637
current_received_ts = 156.424072
current_seq = 1428
count = 80
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.407654/1427, current 156.424637/1428: elapsed=16985.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.424637
last_received_ts = 156.424072
last_seq = 1428
current_ts = 156.441620
current_received_ts = 156.441055
current_seq = 1429
count = 81
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.424637/1428, current 156.441620/1429: elapsed=16985.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.441620
last_received_ts = 156.441055
last_seq = 1429
current_ts = 156.458603
current_received_ts = 156.457977
current_seq = 1430
count = 82
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.441620/1429, current 156.458603/1430: elapsed=16988.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.458603
last_received_ts = 156.457977
last_seq = 1430
current_ts = 156.475586
current_received_ts = 156.475037
current_seq = 1431
count = 83
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.458603/1430, current 156.475586/1431: elapsed=16984.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.475586
last_received_ts = 156.475037
last_seq = 1431
current_ts = 156.492584
current_received_ts = 156.491989
current_seq = 1432
count = 84
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.475586/1431, current 156.492584/1432: elapsed=16985.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.492584
last_received_ts = 156.491989
last_seq = 1432
current_ts = 156.509567
current_received_ts = 156.508942
current_seq = 1433
count = 85
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.492584/1432, current 156.509567/1433: elapsed=16991.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.509567
last_received_ts = 156.508942
last_seq = 1433
current_ts = 156.526550
current_received_ts = 156.525925
current_seq = 1434
count = 86
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.509567/1433, current 156.526550/1434: elapsed=16989.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.526550
last_received_ts = 156.525925
last_seq = 1434
current_ts = 156.543533
current_received_ts = 156.542938
current_seq = 1435
count = 87
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.526550/1434, current 156.543533/1435: elapsed=16975.0us expected=17022.8us +- 85.1us, error 0.3%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.543533
last_received_ts = 156.542938
last_seq = 1435
current_ts = 156.560516
current_received_ts = 156.559906
current_seq = 1436
count = 88
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.543533/1435, current 156.560516/1436: elapsed=16986.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.560516
last_received_ts = 156.559906
last_seq = 1436
current_ts = 156.577515
current_received_ts = 156.576904
current_seq = 1437
count = 89
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.560516/1436, current 156.577515/1437: elapsed=17002.0us expected=17022.8us +- 85.1us, error 0.1%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.577515
last_received_ts = 156.576904
last_seq = 1437
current_ts = 156.594513
current_received_ts = 156.593887
current_seq = 1438
count = 90
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.577515/1437, current 156.594513/1438: elapsed=16987.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.594513
last_received_ts = 156.593887
last_seq = 1438
current_ts = 156.611481
current_received_ts = 156.610901
current_seq = 1439
count = 91
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.594513/1438, current 156.611481/1439: elapsed=16971.0us expected=17022.8us +- 85.1us, error 0.3%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.611481
last_received_ts = 156.610901
last_seq = 1439
current_ts = 156.628464
current_received_ts = 156.627869
current_seq = 1440
count = 92
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.611481/1439, current 156.628464/1440: elapsed=16986.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.628464
last_received_ts = 156.627869
last_seq = 1440
current_ts = 156.645447
current_received_ts = 156.644882
current_seq = 1441
count = 93
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.628464/1440, current 156.645447/1441: elapsed=16985.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.645447
last_received_ts = 156.644882
last_seq = 1441
current_ts = 156.662430
current_received_ts = 156.661804
current_seq = 1442
count = 94
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.645447/1441, current 156.662430/1442: elapsed=16983.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.662430
last_received_ts = 156.661804
last_seq = 1442
current_ts = 156.679413
current_received_ts = 156.678848
current_seq = 1443
count = 95
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.662430/1442, current 156.679413/1443: elapsed=16988.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.679413
last_received_ts = 156.678848
last_seq = 1443
current_ts = 156.696396
current_received_ts = 156.695816
current_seq = 1444
count = 96
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.679413/1443, current 156.696396/1444: elapsed=16983.0us expected=17022.8us +- 85.1us, error 0.2%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.696396
last_received_ts = 156.695816
last_seq = 1444
current_ts = 156.713364
current_received_ts = 156.712769
current_seq = 1445
count = 97
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.696396/1444, current 156.713364/1445: elapsed=16968.0us expected=17022.8us +- 85.1us, error 0.3%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.713364
last_received_ts = 156.712769
last_seq = 1445
current_ts = 156.730331
current_received_ts = 156.729752
current_seq = 1446
count = 98
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.713364/1445, current 156.730331/1446: elapsed=16953.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.730331
last_received_ts = 156.729752
last_seq = 1446
current_ts = 156.747284
current_received_ts = 156.746719
current_seq = 1447
count = 99
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.730331/1446, current 156.747284/1447: elapsed=16954.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.747284
last_received_ts = 156.746719
last_seq = 1447
current_ts = 156.764236
current_received_ts = 156.763641
current_seq = 1448
count = 100
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.747284/1447, current 156.764236/1448: elapsed=16952.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.764236
last_received_ts = 156.763641
last_seq = 1448
current_ts = 156.781189
current_received_ts = 156.780563
current_seq = 1449
count = 101
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.764236/1448, current 156.781189/1449: elapsed=16953.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.781189
last_received_ts = 156.780563
last_seq = 1449
current_ts = 156.798141
current_received_ts = 156.797546
current_seq = 1450
count = 102
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.781189/1449, current 156.798141/1450: elapsed=16954.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.798141
last_received_ts = 156.797546
last_seq = 1450
current_ts = 156.815094
current_received_ts = 156.814499
current_seq = 1451
count = 103
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.798141/1450, current 156.815094/1451: elapsed=16957.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.815094
last_received_ts = 156.814499
last_seq = 1451
current_ts = 156.832047
current_received_ts = 156.831375
current_seq = 1452
count = 104
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.815094/1451, current 156.832047/1452: elapsed=16945.0us expected=17022.8us +- 85.1us, error 0.5%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.832047
last_received_ts = 156.831375
last_seq = 1452
current_ts = 156.848999
current_received_ts = 156.848358
current_seq = 1453
count = 105
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.832047/1452, current 156.848999/1453: elapsed=16960.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.848999
last_received_ts = 156.848358
last_seq = 1453
current_ts = 156.865952
current_received_ts = 156.865326
current_seq = 1454
count = 106
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.848999/1453, current 156.865952/1454: elapsed=16960.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.865952
last_received_ts = 156.865326
last_seq = 1454
current_ts = 156.882904
current_received_ts = 156.882248
current_seq = 1455
count = 107
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.865952/1454, current 156.882904/1455: elapsed=16940.0us expected=17022.8us +- 85.1us, error 0.5%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.882904
last_received_ts = 156.882248
last_seq = 1455
current_ts = 156.899857
current_received_ts = 156.899155
current_seq = 1456
count = 108
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.882904/1455, current 156.899857/1456: elapsed=16963.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.899857
last_received_ts = 156.899155
last_seq = 1456
current_ts = 156.916809
current_received_ts = 156.916153
current_seq = 1457
count = 109
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.899857/1456, current 156.916809/1457: elapsed=16948.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.916809
last_received_ts = 156.916153
last_seq = 1457
current_ts = 156.933762
current_received_ts = 156.933151
current_seq = 1458
count = 110
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.916809/1457, current 156.933762/1458: elapsed=16952.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.933762
last_received_ts = 156.933151
last_seq = 1458
current_ts = 156.950714
current_received_ts = 156.950134
current_seq = 1459
count = 111
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.933762/1458, current 156.950714/1459: elapsed=16953.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.950714
last_received_ts = 156.950134
last_seq = 1459
current_ts = 156.967667
current_received_ts = 156.967087
current_seq = 1460
count = 112
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.950714/1459, current 156.967667/1460: elapsed=16952.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.967667
last_received_ts = 156.967087
last_seq = 1460
current_ts = 156.984619
current_received_ts = 156.984055
current_seq = 1461
count = 113
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.967667/1460, current 156.984619/1461: elapsed=16958.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 156.984619
last_received_ts = 156.984055
last_seq = 1461
current_ts = 157.001587
current_received_ts = 157.000961
current_seq = 1462
count = 114
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 156.984619/1461, current 157.001587/1462: elapsed=16963.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.001587
last_received_ts = 157.000961
last_seq = 1462
current_ts = 157.018524
current_received_ts = 157.017944
current_seq = 1463
count = 115
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.001587/1462, current 157.018524/1463: elapsed=16938.0us expected=17022.8us +- 85.1us, error 0.5%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.018524
last_received_ts = 157.017944
last_seq = 1463
current_ts = 157.035477
current_received_ts = 157.034897
current_seq = 1464
count = 116
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.018524/1463, current 157.035477/1464: elapsed=16953.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.035477
last_received_ts = 157.034897
last_seq = 1464
current_ts = 157.052444
current_received_ts = 157.051880
current_seq = 1465
count = 117
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.035477/1464, current 157.052444/1465: elapsed=16959.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.052444
last_received_ts = 157.051880
last_seq = 1465
current_ts = 157.069382
current_received_ts = 157.068802
current_seq = 1466
count = 118
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.052444/1465, current 157.069382/1466: elapsed=16948.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.069382
last_received_ts = 157.068802
last_seq = 1466
current_ts = 157.086334
current_received_ts = 157.085785
current_seq = 1467
count = 119
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.069382/1466, current 157.086334/1467: elapsed=16952.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.086334
last_received_ts = 157.085785
last_seq = 1467
current_ts = 157.103287
current_received_ts = 157.102737
current_seq = 1468
count = 120
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.086334/1467, current 157.103287/1468: elapsed=16955.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.103287
last_received_ts = 157.102737
last_seq = 1468
current_ts = 157.120255
current_received_ts = 157.119690
current_seq = 1469
count = 121
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.103287/1468, current 157.120255/1469: elapsed=16957.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.120255
last_received_ts = 157.119690
last_seq = 1469
current_ts = 157.137207
current_received_ts = 157.136612
current_seq = 1470
count = 122
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.120255/1469, current 157.137207/1470: elapsed=16955.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.137207
last_received_ts = 157.136612
last_seq = 1470
current_ts = 157.154144
current_received_ts = 157.153580
current_seq = 1471
count = 123
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.137207/1470, current 157.154144/1471: elapsed=16946.0us expected=17022.8us +- 85.1us, error 0.5%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.154144
last_received_ts = 157.153580
last_seq = 1471
current_ts = 157.171112
current_received_ts = 157.170456
current_seq = 1472
count = 124
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.154144/1471, current 157.171112/1472: elapsed=16958.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.171112
last_received_ts = 157.170456
last_seq = 1472
current_ts = 157.188065
current_received_ts = 157.187332
current_seq = 1473
count = 125
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.171112/1472, current 157.188065/1473: elapsed=16949.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.188065
last_received_ts = 157.187332
last_seq = 1473
current_ts = 157.205017
current_received_ts = 157.204315
current_seq = 1474
count = 126
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.188065/1473, current 157.205017/1474: elapsed=16953.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.205017
last_received_ts = 157.204315
last_seq = 1474
current_ts = 157.221970
current_received_ts = 157.221268
current_seq = 1475
count = 127
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.205017/1474, current 157.221970/1475: elapsed=16954.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.221970
last_received_ts = 157.221268
last_seq = 1475
current_ts = 157.238922
current_received_ts = 157.238251
current_seq = 1476
count = 128
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.221970/1475, current 157.238922/1476: elapsed=16958.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.238922
last_received_ts = 157.238251
last_seq = 1476
current_ts = 157.255875
current_received_ts = 157.255325
current_seq = 1477
count = 129
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.238922/1476, current 157.255875/1477: elapsed=16951.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.255875
last_received_ts = 157.255325
last_seq = 1477
current_ts = 157.272827
current_received_ts = 157.272278
current_seq = 1478
count = 130
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.255875/1477, current 157.272827/1478: elapsed=16954.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.272827
last_received_ts = 157.272278
last_seq = 1478
current_ts = 157.289795
current_received_ts = 157.289169
current_seq = 1479
count = 131
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.272827/1478, current 157.289795/1479: elapsed=16964.0us expected=17022.8us +- 85.1us, error 0.3%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.289795
last_received_ts = 157.289169
last_seq = 1479
current_ts = 157.306732
current_received_ts = 157.306183
current_seq = 1480
count = 132
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.289795/1479, current 157.306732/1480: elapsed=16943.0us expected=17022.8us +- 85.1us, error 0.5%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.306732
last_received_ts = 157.306183
last_seq = 1480
current_ts = 157.323685
current_received_ts = 157.323135
current_seq = 1481
count = 133
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.306732/1480, current 157.323685/1481: elapsed=16947.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.323685
last_received_ts = 157.323135
last_seq = 1481
current_ts = 157.340637
current_received_ts = 157.340073
current_seq = 1482
count = 134
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.323685/1481, current 157.340637/1482: elapsed=16958.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.340637
last_received_ts = 157.340073
last_seq = 1482
current_ts = 157.357590
current_received_ts = 157.356979
current_seq = 1483
count = 135
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.340637/1482, current 157.357590/1483: elapsed=16950.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.357590
last_received_ts = 157.356979
last_seq = 1483
current_ts = 157.374542
current_received_ts = 157.373993
current_seq = 1484
count = 136
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.357590/1483, current 157.374542/1484: elapsed=16952.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.374542
last_received_ts = 157.373993
last_seq = 1484
current_ts = 157.391495
current_received_ts = 157.390945
current_seq = 1485
count = 137
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.374542/1484, current 157.391495/1485: elapsed=16952.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.391495
last_received_ts = 157.390945
last_seq = 1485
current_ts = 157.408463
current_received_ts = 157.407806
current_seq = 1486
count = 138
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.391495/1485, current 157.408463/1486: elapsed=16961.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.408463
last_received_ts = 157.407806
last_seq = 1486
current_ts = 157.425400
current_received_ts = 157.424774
current_seq = 1487
count = 139
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.408463/1486, current 157.425400/1487: elapsed=16952.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.425400
last_received_ts = 157.424774
last_seq = 1487
current_ts = 157.442352
current_received_ts = 157.441727
current_seq = 1488
count = 140
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.425400/1487, current 157.442352/1488: elapsed=16942.0us expected=17022.8us +- 85.1us, error 0.5%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.442352
last_received_ts = 157.441727
last_seq = 1488
current_ts = 157.459305
current_received_ts = 157.458725
current_seq = 1489
count = 141
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.442352/1488, current 157.459305/1489: elapsed=16955.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.459305
last_received_ts = 157.458725
last_seq = 1489
current_ts = 157.476273
current_received_ts = 157.475693
current_seq = 1490
count = 142
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.459305/1489, current 157.476273/1490: elapsed=16970.0us expected=17022.8us +- 85.1us, error 0.3%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.476273
last_received_ts = 157.475693
last_seq = 1490
current_ts = 157.493225
current_received_ts = 157.492645
current_seq = 1491
count = 143
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.476273/1490, current 157.493225/1491: elapsed=16952.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.493225
last_received_ts = 157.492645
last_seq = 1491
current_ts = 157.510162
current_received_ts = 157.509567
current_seq = 1492
count = 144
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.493225/1491, current 157.510162/1492: elapsed=16942.0us expected=17022.8us +- 85.1us, error 0.5%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.510162
last_received_ts = 157.509567
last_seq = 1492
current_ts = 157.527115
current_received_ts = 157.526566
current_seq = 1493
count = 145
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.510162/1492, current 157.527115/1493: elapsed=16953.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.527115
last_received_ts = 157.526566
last_seq = 1493
current_ts = 157.544067
current_received_ts = 157.543503
current_seq = 1494
count = 146
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.527115/1493, current 157.544067/1494: elapsed=16950.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.544067
last_received_ts = 157.543503
last_seq = 1494
current_ts = 157.561035
current_received_ts = 157.560410
current_seq = 1495
count = 147
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.544067/1494, current 157.561035/1495: elapsed=16957.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.561035
last_received_ts = 157.560410
last_seq = 1495
current_ts = 157.577972
current_received_ts = 157.577347
current_seq = 1496
count = 148
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.561035/1495, current 157.577972/1496: elapsed=16952.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.577972
last_received_ts = 157.577347
last_seq = 1496
current_ts = 157.594940
current_received_ts = 157.594315
current_seq = 1497
count = 149
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.577972/1496, current 157.594940/1497: elapsed=16967.0us expected=17022.8us +- 85.1us, error 0.3%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.594940
last_received_ts = 157.594315
last_seq = 1497
current_ts = 157.611893
current_received_ts = 157.611221
current_seq = 1498
count = 150
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.594940/1497, current 157.611893/1498: elapsed=16947.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.611893
last_received_ts = 157.611221
last_seq = 1498
current_ts = 157.628860
current_received_ts = 157.628189
current_seq = 1499
count = 151
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.611893/1498, current 157.628860/1499: elapsed=16960.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.628860
last_received_ts = 157.628189
last_seq = 1499
current_ts = 157.645798
current_received_ts = 157.645203
current_seq = 1500
count = 152
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.628860/1499, current 157.645798/1500: elapsed=16943.0us expected=17022.8us +- 85.1us, error 0.5%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.645798
last_received_ts = 157.645203
last_seq = 1500
current_ts = 157.662750
current_received_ts = 157.662155
current_seq = 1501
count = 153
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.645798/1500, current 157.662750/1501: elapsed=16948.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.662750
last_received_ts = 157.662155
last_seq = 1501
current_ts = 157.679703
current_received_ts = 157.679108
current_seq = 1502
count = 154
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.662750/1501, current 157.679703/1502: elapsed=16957.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.679703
last_received_ts = 157.679108
last_seq = 1502
current_ts = 157.696655
current_received_ts = 157.696075
current_seq = 1503
count = 155
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.679703/1502, current 157.696655/1503: elapsed=16949.0us expected=17022.8us +- 85.1us, error 0.4%
(kms_flip:2632) DEBUG: name = flip
last_ts = 157.696655
last_received_ts = 157.696075
last_seq = 1503
current_ts = 157.713593
current_received_ts = 157.712982
current_seq = 1504
count = 156
seq_step = 1
(kms_flip:2632) DEBUG: flip ts/seq: last 157.696655/1503, current 157.713593/1504: elapsed=16937.0us expected=17022.8us +- 85.1us, error 0.5%
(kms_flip:2632) DEBUG: inconsistent flip ts/seq: last 157.696655/1503, current 157.713593/1504: elapsed=16937.0us expected=17022.8us
(kms_flip:2632) DEBUG: expected 63475, counted 156, encoder type 1
(kms_flip:2632) DEBUG: dropped frames, expected 63475, counted 156, encoder type 1
(kms_flip:2632) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:2632) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
(kms_flip:2632) igt_core-INFO: Stack trace:
(kms_flip:2632) igt_core-INFO:   #0 ../lib/igt_core.c:2051 __igt_fail_assert()
(kms_flip:2632) igt_core-INFO:   #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0()
(kms_flip:2632) igt_core-INFO:   #2 ../tests/kms_flip.c:1845 run_test()
(kms_flip:2632) igt_core-INFO:   #3 ../tests/kms_flip.c:2078 __igt_unique____real_main2001()
(kms_flip:2632) igt_core-INFO:   #4 ../tests/kms_flip.c:2001 main()
(kms_flip:2632) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2632) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2632) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-VGA1: FAIL (6.314s)
Dmesg
<6> [154.252378] Console: switching to colour dummy device 80x25
<6> [154.252465] [IGT] kms_flip: executing
<7> [154.256366] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [154.257864] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [154.258263] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [154.263195] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1]
<7> [154.263228] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:64:VGA-1] force=1
<7> [154.263587] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] ironlake hotplug adpa=0xf40000, result 0
<7> [154.263887] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT not detected via hotplug
<7> [154.264299] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [154.264594] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7> [154.265045] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [154.265272] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [154.265278] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7> [154.265505] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7> [154.266790] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [154.266796] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7> [154.266999] i915 0000:00:02.0: [drm:intel_crt_detect_ddc [i915]] CRT not detected via DDC:0x50 [no valid EDID found]
<7> [154.267206] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1] disconnected
<7> [154.267747] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:75:DP-1]
<7> [154.267766] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:75:DP-1]
<7> [154.271581] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.275388] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.279322] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.283240] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.287088] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.290843] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.294689] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.298607] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.302484] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.306392] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.310357] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.314241] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.318000] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.321900] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.325878] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.330042] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.334234] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.338377] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.342550] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.346708] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.350857] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.355024] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.359180] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.363321] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.367234] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.370934] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.375069] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.379325] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.383636] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.387846] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.391956] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.395988] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.396425] i915 0000:00:02.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX D/DP D: Too many retries, giving up. First error: -110
<7> [154.396461] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:75:DP-1] disconnected
<7> [154.396853] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1]
<7> [154.396880] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:64:VGA-1] force=1
<7> [154.397228] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] ironlake hotplug adpa=0xf40000, result 0
<7> [154.397568] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT not detected via hotplug
<7> [154.398200] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [154.398860] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7> [154.399645] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [154.399976] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [154.399985] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7> [154.400310] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7> [154.401828] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [154.401837] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7> [154.402158] i915 0000:00:02.0: [drm:intel_crt_detect_ddc [i915]] CRT not detected via DDC:0x50 [no valid EDID found]
<7> [154.402485] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1] disconnected
<7> [154.402880] i915 0000:00:02.0: [drm:status_store] [CONNECTOR:64:VGA-1] force updated from 0 to 2 or reprobing
<7> [154.402893] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1]
<7> [154.402922] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1] status updated from disconnected to connected
<7> [154.402970] i915 0000:00:02.0: [drm:drm_sysfs_hotplug_event] generating hotplug event
<7> [154.403086] i915 0000:00:02.0: [drm:drm_client_dev_hotplug] intel-fbdev: ret=0
<7> [154.403330] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [154.403688] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7> [154.404174] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [154.404522] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [154.404538] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7> [154.404902] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7> [154.406497] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [154.406514] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7> [154.406946] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1] probed modes:
<7> [154.406974] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [154.407003] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5
<7> [154.407030] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 56 36000 800 824 896 1024 600 601 603 625 0x40 0x5
<7> [154.407058] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "848x480": 60 33750 848 864 976 1088 480 486 494 517 0x40 0x5
<7> [154.407086] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7> [154.407167] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1]
<7> [154.407416] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [154.407630] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7> [154.408029] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [154.408346] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [154.408375] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7> [154.408669] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7> [154.410029] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [154.410040] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7> [154.410475] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1] probed modes:
<7> [154.410502] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [154.410529] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5
<7> [154.410577] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 56 36000 800 824 896 1024 600 601 603 625 0x40 0x5
<7> [154.410598] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "848x480": 60 33750 848 864 976 1088 480 486 494 517 0x40 0x5
<7> [154.410612] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7> [154.410644] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:67:HDMI-A-1]
<7> [154.410661] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:67:HDMI-A-1]
<7> [154.436538] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] HDMI: DVI dual 0, max TMDS clock 340000 kHz
<7> [154.436550] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] HDMI sink does deep color 30.
<7> [154.436556] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] HDMI sink does deep color 36.
<7> [154.436562] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] Assigning HDMI sink color depth as 12 bpc.
<7> [154.436568] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] HDMI sink does YCRCB444 in deep color.
<7> [154.436574] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] HF-VSDB: max TMDS clock: 600000 KHz, HDMI 2.1 support: no, DSC 1.2 support: no
<7> [154.436582] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] ELD monitor 28E850
<7> [154.436589] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] HDMI: latency present 0 0, video latency 0 0, audio latency 0 0
<7> [154.436596] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] ELD size 32, SAD count 1
<7> [154.436865] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK for addr: 0040 w(1)
<7> [154.437164] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK on first message, retry
<7> [154.437641] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK for addr: 0040 w(1)
<7> [154.438098] i915 0000:00:02.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [154.439899] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "2560x1600": 60 268500 2560 2608 2640 2720 1600 1603 1609 1646 0x40 0x9 (CLOCK_HIGH)
<7> [154.439915] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "2560x1440": 60 241500 2560 2608 2640 2720 1440 1443 1449 1481 0x40 0x9 (CLOCK_HIGH)
<7> [154.439926] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "2880x1800": 60 336720 2880 2928 2960 3040 1800 1803 1809 1846 0x40 0x9 (CLOCK_HIGH)
<7> [154.439936] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 30 297000 3840 3888 3920 4400 2160 2164 2170 2250 0x40 0x9 (CLOCK_HIGH)
<7> [154.439955] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 60 594000 3840 4016 4104 4400 2160 2162 2172 2250 0x40 0x5 (CLOCK_HIGH)
<7> [154.439966] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 60 594000 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [154.439978] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080": 120 297000 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5 (CLOCK_HIGH)
<7> [154.439989] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 50 594000 3840 4896 4984 5280 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [154.440000] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "4096x2160": 50 594000 4096 5064 5152 5280 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [154.440011] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "4096x2160": 60 594000 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [154.440023] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 24 297000 3840 5116 5204 5500 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [154.440034] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 25 297000 3840 4896 4984 5280 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [154.440045] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 30 297000 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [154.440056] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "4096x2160": 24 297000 4096 5116 5204 5500 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [154.440067] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 60 593407 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [154.440079] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080": 120 296703 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5 (CLOCK_HIGH)
<7> [154.440090] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "4096x2160": 60 593407 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [154.440101] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 24 296703 3840 5116 5204 5500 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [154.440112] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 30 296703 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [154.440123] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "4096x2160": 24 296703 4096 5116 5204 5500 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [154.440140] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:67:HDMI-A-1] probed modes:
<7> [154.440163] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [154.440184] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1200": 60 154000 1920 1968 2000 2080 1200 1203 1209 1235 0x40 0x9
<7> [154.440204] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080": 75 220597 1920 2056 2264 2608 1080 1081 1084 1128 0x0 0x6
<7> [154.440225] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080i": 60 74250 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x15
<7> [154.440245] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080i": 60 74176 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x15
<7> [154.440265] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080": 50 148500 1920 2448 2492 2640 1080 1084 1089 1125 0x40 0x5
<7> [154.440285] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080i": 50 74250 1920 2448 2492 2640 1080 1084 1094 1125 0x40 0x15
<7> [154.440306] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080": 24 74250 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x5
<7> [154.440325] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080": 24 74176 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x5
<7> [154.440345] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1680x1050": 60 119000 1680 1728 1760 1840 1050 1053 1059 1080 0x40 0x9
<7> [154.440365] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1600x900": 60 108000 1600 1624 1704 1800 900 901 904 1000 0x40 0x5
<7> [154.440386] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1280x1024": 75 135000 1280 1296 1440 1688 1024 1025 1028 1066 0x40 0x5
<7> [154.440406] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1280x1024": 61 110809 1280 1360 1496 1712 1024 1025 1028 1061 0x0 0x6
<7> [154.440426] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1440x900": 60 88750 1440 1488 1520 1600 900 903 909 926 0x40 0x9
<7> [154.440446] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1280x800": 60 71000 1280 1328 1360 1440 800 803 809 823 0x40 0x9
<7> [154.440466] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1280x720": 60 74250 1280 1390 1430 1650 720 725 730 750 0x40 0x5
<7> [154.440486] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1280x720": 60 74250 1280 1390 1430 1650 720 725 730 750 0x40 0x5
<7> [154.440506] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1280x720": 60 74176 1280 1390 1430 1650 720 725 730 750 0x40 0x5
<7> [154.440525] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1280x720": 50 74250 1280 1720 1760 1980 720 725 730 750 0x40 0x5
<7> [154.440545] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1024x768": 75 78750 1024 1040 1136 1312 768 769 772 800 0x40 0x5
<7> [154.440565] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1024x768": 70 75000 1024 1048 1184 1328 768 771 777 806 0x40 0xa
<7> [154.440585] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [154.440605] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 75 49500 800 816 896 1056 600 601 604 625 0x40 0x5
<7> [154.440625] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 72 50000 800 856 976 1040 600 637 643 666 0x40 0x5
<7> [154.440645] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5
<7> [154.440664] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 56 36000 800 824 896 1024 600 601 603 625 0x40 0x5
<7> [154.440684] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "720x576": 50 27000 720 732 796 864 576 581 586 625 0x40 0xa
<7> [154.440722] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "720x480": 60 27027 720 736 798 858 480 489 495 525 0x40 0xa
<7> [154.440750] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "720x480": 60 27000 720 736 798 858 480 489 495 525 0x40 0xa
<7> [154.440768] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "640x480": 75 31500 640 656 720 840 480 481 484 500 0x40 0xa
<7> [154.440785] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "640x480": 73 31500 640 664 704 832 480 489 492 520 0x40 0xa
<7> [154.440802] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "640x480": 60 25200 640 656 752 800 480 490 492 525 0x40 0xa
<7> [154.440819] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7> [154.440836] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7> [154.440963] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:75:DP-1]
<7> [154.440982] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:75:DP-1]
<7> [154.444779] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.448623] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.452371] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.456277] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.460018] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.463923] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.467833] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.471760] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.475676] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.479552] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.483458] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.487344] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.491128] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.495019] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.498832] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.502598] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.506492] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.510252] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.514182] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.518370] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.522534] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.526689] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.530833] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.535007] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.538978] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.543118] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.547249] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.551409] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.555559] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.559770] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.563923] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.568054] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [154.568526] i915 0000:00:02.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX D/DP D: Too many retries, giving up. First error: -110
<7> [154.568572] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:75:DP-1] disconnected
<6> [154.569784] [IGT] kms_flip: starting subtest plain-flip-fb-recreate
<6> [154.571428] [IGT] kms_flip: starting dynamic subtest A-VGA1
<7> [154.571902] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [154.572027] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [154.622254] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:47:pipe A]
<7> [154.622465] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [154.623031] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [154.623370] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [154.623584] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in has_pch_encoder (expected yes, found no)
<7> [154.623797] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in fdi_lanes (expected 3, found 0)
<7> [154.624085] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in fdi_m_n (expected tu 64 data 6934582/8388608 link 288940/524288, found tu 0, data 0/0 link 0/0)
<7> [154.624311] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [154.624524] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [154.624755] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [154.624994] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [154.625226] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [154.625439] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [154.625663] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [154.625915] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [154.626165] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [154.626378] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [154.626590] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [154.626812] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [154.627051] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [154.627317] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [154.627530] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [154.627743] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [154.628003] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [154.628243] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [154.628456] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [154.628683] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [154.628923] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [154.629157] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [154.629385] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [154.629597] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [154.629847] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [154.630080] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [154.630293] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [154.630517] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [154.630750] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [154.631016] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [154.631229] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [154.631442] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [154.631667] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [154.631906] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148800, found 0)
<7> [154.632156] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148800, found 0)
<7> [154.632369] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in port_clock (expected 223200, found 0)
<7> [154.632581] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [154.632832] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [154.633071] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in avi infoframe
<7> [154.633295] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [154.633513] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [154.633516] i915 0000:00:02.0: colorspace: RGB
<7> [154.633518] i915 0000:00:02.0: scan mode: Underscan
<7> [154.633520] i915 0000:00:02.0: colorimetry: No Data
<7> [154.633522] i915 0000:00:02.0: picture aspect: No Data
<7> [154.633525] i915 0000:00:02.0: active aspect: Same as Picture
<7> [154.633527] i915 0000:00:02.0: itc: No Data
<7> [154.633529] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [154.633531] i915 0000:00:02.0: quantization range: Full
<7> [154.633533] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [154.633535] i915 0000:00:02.0: video code: 0
<7> [154.633538] i915 0000:00:02.0: ycc quantization range: Full
<7> [154.633540] i915 0000:00:02.0: hdmi content type: Graphics
<7> [154.633542] i915 0000:00:02.0: pixel repeat: 0
<7> [154.633544] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [154.633547] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [154.633794] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in spd infoframe
<7> [154.634041] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [154.634268] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [154.634271] i915 0000:00:02.0: vendor: Intel
<7> [154.634273] i915 0000:00:02.0: product: Integrated gfx
<7> [154.634275] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [154.634278] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [154.634490] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hdmi infoframe
<7> [154.634755] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [154.635060] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [154.635062] i915 0000:00:02.0: empty frame
<7> [154.635064] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [154.635277] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:47:pipe A] fastset requirement not met, forcing full modeset
<7> [154.635491] i915 0000:00:02.0: [drm:intel_unreference_shared_dpll_crtc [i915]] [CRTC:47:pipe A] releasing PCH DPLL A
<7> [154.635808] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 400000 kHz, actual 400000 kHz
<7> [154.636024] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [154.636272] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:47:pipe A] enable: no [modeset]
<7> [154.636492] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:primary A] fb: [NOFB], visible: no
<7> [154.636799] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:67:HDMI-A-1][ENCODER:66:HDMI D] Disable audio codec on [CRTC:47:pipe A]
<7> [154.662012] i915 0000:00:02.0: [drm:i915_audio_component_get_eld [i915]] Not valid for port D
<7> [154.662796] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [154.678844] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found
<7> [154.679289] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found
<7> [154.679743] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found
<7> [154.691164] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disable PCH DPLL A (active 0x1, on? 1) for [CRTC:47:pipe A]
<7> [154.691687] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disabling PCH DPLL A
<7> [154.692382] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:65:CRT]
<7> [154.692835] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:66:HDMI D]
<7> [154.693272] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:DP D]
<7> [154.693758] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:67:HDMI-A-1]
<7> [154.694319] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:47:pipe A]
<7> [154.694693] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:63:pipe B]
<7> [154.694829] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:47:pipe A]
<7> [154.694856] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:64:VGA-1]
<7> [154.694940] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:64:VGA-1] Limiting display bpp to 24 (EDID bpp 0, max requested bpp 0, max platform bpp 36)
<7> [154.695170] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:47:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [154.695403] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe A, lanes 1
<7> [154.695659] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [154.695878] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [154.696098] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [154.696317] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in has_pch_encoder (expected no, found yes)
<7> [154.696549] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in fdi_lanes (expected 0, found 1)
<7> [154.696769] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in fdi_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6039797/8388608 link 125829/524288)
<7> [154.696989] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000002)
<7> [154.697208] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [154.697454] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1024)
<7> [154.697673] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 1344)
<7> [154.697892] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1024)
<7> [154.698110] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 1344)
<7> [154.698337] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1048)
<7> [154.698563] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 1184)
<7> [154.698781] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 768)
<7> [154.699000] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 768)
<7> [154.699223] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 771)
<7> [154.699459] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 777)
<7> [154.699700] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 806)
<7> [154.699918] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 806)
<7> [154.700137] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1024)
<7> [154.700378] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 1344)
<7> [154.700597] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1024)
<7> [154.700816] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 1344)
<7> [154.701035] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1048)
<7> [154.701253] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 1184)
<7> [154.701500] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 768)
<7> [154.701720] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 768)
<7> [154.701938] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 771)
<7> [154.702156] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 777)
<7> [154.702389] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 806)
<7> [154.702608] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 806)
<7> [154.702827] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [154.703045] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [154.703278] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [154.703522] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in dpll_hw_state
<7> [154.703751] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [154.703963] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0xc4010001, dpll_md: 0x0, fp0: 0x30f06, fp1: 0x30f06
<7> [154.704175] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [154.704418] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [154.704648] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [154.704860] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 64800)
<7> [154.705073] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 64800)
<7> [154.705345] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in port_clock (expected 0, found 64800)
<7> [154.705578] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:47:pipe A] fastset requirement not met, forcing full modeset
<7> [154.705846] i915 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [i915]] [PLANE:32:primary A] min cdclk (64800 kHz) > [CRTC:47:pipe A] min cdclk (0 kHz)
<7> [154.706070] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 400000 kHz, actual 400000 kHz
<7> [154.706317] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [154.706548] i915 0000:00:02.0: [drm:intel_find_shared_dpll [i915]] [CRTC:47:pipe A] allocated PCH DPLL A
<7> [154.706772] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc [i915]] [CRTC:47:pipe A] reserving PCH DPLL A
<7> [154.706990] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:47:pipe A] enable: yes [modeset]
<7> [154.707231] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB
<7> [154.707460] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [154.707698] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [154.707916] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [154.708147] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [154.708366] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [154.708585] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] fdi: lanes: 1; data_m: 6039797, data_n: 8388608, link_m: 125829, link_n: 524288, tu: 64
<7> [154.708804] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0
<7> [154.709022] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [154.709283] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, vmin: 0, vmax: 0, pipeline full: 0, guardband: 0 flipline: 0, vmin vblank: -1, vmax vblank: -2
<7> [154.709504] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [154.709724] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [154.709944] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=64800, hd=1024 hb=1024-1344 hs=1048-1184 ht=1344, vd=768 vb=768-806 vs=771-777 vt=806, flags=0xa
<7> [154.710191] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1024x768": 60 64800 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [154.710412] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=64800, hd=1024 hb=1024-1344 hs=1048-1184 ht=1344, vd=768 vb=768-806 vs=771-777 vt=806, flags=0xa
<7> [154.710631] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 64800, pipe src: 1024x768+0+0, pixel rate 64800
<7> [154.710850] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0
<7> [154.711083] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [154.711324] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [154.711536] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [154.711760] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [154.711979] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [154.712226] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [154.712243] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [154.712473] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [154.712685] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [154.712912] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [154.713162] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:primary A] fb: [FB:80] 1024x768 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [154.713404] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [154.713626] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0
<7> [154.714775] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [154.715031] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:65:CRT]
<7> [154.715243] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:66:HDMI D]
<7> [154.715452] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:DP D]
<7> [154.716228] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [154.717284] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100
<7> [154.717492] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done.
<7> [154.718356] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x200
<7> [154.718564] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done.
<7> [154.718768] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train done.
<7> [154.719015] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enable PCH DPLL A (active 0x1, on? 0) for [CRTC:47:pipe A]
<7> [154.719242] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling PCH DPLL A
<7> [154.771307] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:64:VGA-1]
<7> [154.771822] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:47:pipe A]
<7> [155.072254] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [155.088988] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [155.105715] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [155.122418] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [155.139146] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [155.155913] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [155.172605] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [155.189311] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [155.206046] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [155.222752] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [155.239463] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [155.256193] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [155.272890] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [155.289618] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [155.306365] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [155.323097] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [155.339736] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [155.356501] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [155.373207] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [155.389937] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [155.406571] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [155.423347] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [155.440063] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [155.456780] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [155.473498] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [155.490195] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [155.506943] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [155.523666] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [155.540359] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [155.557015] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [155.573799] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [155.590516] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [155.607036] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [155.624102] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [155.640460] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [155.657279] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [155.674086] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [155.690820] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [155.707497] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [155.724246] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [155.740961] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [155.757684] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [155.774387] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [155.791122] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [155.807813] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [155.824499] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [155.841249] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [155.857945] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [155.874685] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [155.891390] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [155.908127] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [155.924883] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [155.941528] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [155.958286] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [155.975015] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [155.991723] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [156.008417] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [156.025167] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [156.041816] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [156.058566] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [156.075317] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [156.092032] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [156.108761] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [156.125468] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [156.142172] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [156.158794] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [156.175581] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [156.192260] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [156.209415] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [156.225669] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [156.242429] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [156.259139] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [156.275815] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [156.292583] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [156.309299] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [156.326016] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [156.342714] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [156.359801] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [156.376170] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [156.392861] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [156.409611] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [156.426340] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [156.443054] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [156.459739] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [156.476502] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [156.493211] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [156.509900] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [156.526578] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [156.543351] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [156.560021] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [156.576755] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [156.593547] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [156.610315] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [156.626963] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [156.643733] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [156.660348] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [156.677125] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [156.693830] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [156.710524] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [156.727279] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [156.743999] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [156.760647] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [156.777345] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [156.794087] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [156.810808] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [156.827443] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [156.844188] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [156.860922] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [156.877612] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [156.894275] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [156.911029] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [156.911084] i915 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:84] from all active usage due to RMFB ioctl
<7> [156.927796] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [156.944544] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [156.961260] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [156.977994] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [156.994686] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [157.011433] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [157.028126] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [157.044962] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [157.061586] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [157.078343] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [157.095056] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [157.111774] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [157.128469] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [157.145204] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [157.161812] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [157.178410] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [157.195143] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [157.211867] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [157.228660] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [157.245482] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [157.262198] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [157.278863] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [157.295630] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [157.312360] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [157.329074] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [157.345773] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [157.362529] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [157.379245] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [157.395803] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [157.412566] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [157.429294] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [157.446052] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [157.462779] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [157.479523] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [157.496194] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [157.512945] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [157.529655] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [157.546325] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [157.563022] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [157.579768] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [157.596422] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [157.613155] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [157.629938] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [157.646640] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:80]
<7> [157.663374] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83]
<7> [157.680092] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<6> [160.885671] [IGT] kms_flip: finished subtest A-VGA1, FAIL
Created at 2024-12-18 19:20:40