Results for igt@kms_flip@plain-flip-ts-check

Machine description: shard-rkl-3

Result: Fail

git-log-oneline i915_display_info17 igt_runner17 runtimes17 results17.json results17-i915-load.json i915_display_info_post_exec17 boot17 dmesg17

DetailValue
Duration 34.25 seconds
Hostname 
shard-rkl-3
Igt-Version 
IGT-Version: 1.30-gb9b3c2c17 (x86_64) (Linux: 6.14.0-rc6-CI_DRM_16275-g1476c2930768+ x86_64)
Out 
Using IGT_SRANDOM=1741839857 for randomisation
Opened device: /dev/dri/card0
Using monotonic timestamps
Starting subtest: plain-flip-ts-check
Starting dynamic subtest: A-HDMI-A2
  1920x1080: 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5 
Expected frametime: 18974us; measured 18882.9us +- 5.131us accuracy 0.08%
vblank interval differs from modeline! expected 18973.8us, measured 18883us +- 5.131us, difference 90.8us (17.7 sigma)
Stack trace:
  #0 ../lib/igt_core.c:2055 __igt_fail_assert()
  #1 ../tests/kms_flip.c:777 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1831 run_test()
  #3 ../tests/kms_flip.c:2064 __igt_unique____real_main1987()
  #4 ../tests/kms_flip.c:1987 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest A-HDMI-A2: FAIL (18.727s)
Starting dynamic subtest: B-HDMI-A2
  1920x1080: 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5 
Expected frametime: 18974us; measured 18965.7us +- 9.336us accuracy 0.15%
Dynamic subtest B-HDMI-A2: SUCCESS (15.520s)
Subtest plain-flip-ts-check: FAIL (34.251s)
Err 
Starting subtest: plain-flip-ts-check
Starting dynamic subtest: A-HDMI-A2
(kms_flip:2083) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1660:
(kms_flip:2083) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
Dynamic subtest A-HDMI-A2 failed.
**** DEBUG ****
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.226128
last_received_ts = 82.225441
last_seq = 1332
current_ts = 82.245087
current_received_ts = 82.244400
current_seq = 1333
count = 672
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.226128/1332, current 82.245087/1333: elapsed=18958.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.245087
last_received_ts = 82.244400
last_seq = 1333
current_ts = 82.264046
current_received_ts = 82.263351
current_seq = 1334
count = 673
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.245087/1333, current 82.264046/1334: elapsed=18957.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.264046
last_received_ts = 82.263351
last_seq = 1334
current_ts = 82.283005
current_received_ts = 82.282318
current_seq = 1335
count = 674
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.264046/1334, current 82.283005/1335: elapsed=18958.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.283005
last_received_ts = 82.282318
last_seq = 1335
current_ts = 82.301956
current_received_ts = 82.301270
current_seq = 1336
count = 675
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.283005/1335, current 82.301956/1336: elapsed=18957.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.301956
last_received_ts = 82.301270
last_seq = 1336
current_ts = 82.320915
current_received_ts = 82.320221
current_seq = 1337
count = 676
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.301956/1336, current 82.320915/1337: elapsed=18957.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.320915
last_received_ts = 82.320221
last_seq = 1337
current_ts = 82.339874
current_received_ts = 82.339188
current_seq = 1338
count = 677
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.320915/1337, current 82.339874/1338: elapsed=18959.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.339874
last_received_ts = 82.339188
last_seq = 1338
current_ts = 82.358833
current_received_ts = 82.358147
current_seq = 1339
count = 678
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.339874/1338, current 82.358833/1339: elapsed=18958.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.358833
last_received_ts = 82.358147
last_seq = 1339
current_ts = 82.377792
current_received_ts = 82.377106
current_seq = 1340
count = 679
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.358833/1339, current 82.377792/1340: elapsed=18958.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.377792
last_received_ts = 82.377106
last_seq = 1340
current_ts = 82.396751
current_received_ts = 82.396065
current_seq = 1341
count = 680
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.377792/1340, current 82.396751/1341: elapsed=18958.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.396751
last_received_ts = 82.396065
last_seq = 1341
current_ts = 82.415710
current_received_ts = 82.415039
current_seq = 1342
count = 681
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.396751/1341, current 82.415710/1342: elapsed=18958.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.415710
last_received_ts = 82.415039
last_seq = 1342
current_ts = 82.434662
current_received_ts = 82.433968
current_seq = 1343
count = 682
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.415710/1342, current 82.434662/1343: elapsed=18956.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.434662
last_received_ts = 82.433968
last_seq = 1343
current_ts = 82.453621
current_received_ts = 82.452934
current_seq = 1344
count = 683
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.434662/1343, current 82.453621/1344: elapsed=18959.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.453621
last_received_ts = 82.452934
last_seq = 1344
current_ts = 82.472580
current_received_ts = 82.471893
current_seq = 1345
count = 684
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.453621/1344, current 82.472580/1345: elapsed=18958.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.472580
last_received_ts = 82.471893
last_seq = 1345
current_ts = 82.491539
current_received_ts = 82.490852
current_seq = 1346
count = 685
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.472580/1345, current 82.491539/1346: elapsed=18958.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.491539
last_received_ts = 82.490852
last_seq = 1346
current_ts = 82.510498
current_received_ts = 82.509811
current_seq = 1347
count = 686
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.491539/1346, current 82.510498/1347: elapsed=18958.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.510498
last_received_ts = 82.509811
last_seq = 1347
current_ts = 82.529457
current_received_ts = 82.528770
current_seq = 1348
count = 687
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.510498/1347, current 82.529457/1348: elapsed=18958.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.529457
last_received_ts = 82.528770
last_seq = 1348
current_ts = 82.548409
current_received_ts = 82.547722
current_seq = 1349
count = 688
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.529457/1348, current 82.548409/1349: elapsed=18957.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.548409
last_received_ts = 82.547722
last_seq = 1349
current_ts = 82.567368
current_received_ts = 82.566681
current_seq = 1350
count = 689
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.548409/1349, current 82.567368/1350: elapsed=18958.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.567368
last_received_ts = 82.566681
last_seq = 1350
current_ts = 82.586327
current_received_ts = 82.585640
current_seq = 1351
count = 690
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.567368/1350, current 82.586327/1351: elapsed=18957.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.586327
last_received_ts = 82.585640
last_seq = 1351
current_ts = 82.605286
current_received_ts = 82.604599
current_seq = 1352
count = 691
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.586327/1351, current 82.605286/1352: elapsed=18958.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.605286
last_received_ts = 82.604599
last_seq = 1352
current_ts = 82.624245
current_received_ts = 82.623550
current_seq = 1353
count = 692
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.605286/1352, current 82.624245/1353: elapsed=18958.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.624245
last_received_ts = 82.623550
last_seq = 1353
current_ts = 82.643204
current_received_ts = 82.642509
current_seq = 1354
count = 693
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.624245/1353, current 82.643204/1354: elapsed=18958.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.643204
last_received_ts = 82.642509
last_seq = 1354
current_ts = 82.662155
current_received_ts = 82.661469
current_seq = 1355
count = 694
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.643204/1354, current 82.662155/1355: elapsed=18958.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.662155
last_received_ts = 82.661469
last_seq = 1355
current_ts = 82.681114
current_received_ts = 82.680428
current_seq = 1356
count = 695
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.662155/1355, current 82.681114/1356: elapsed=18957.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.681114
last_received_ts = 82.680428
last_seq = 1356
current_ts = 82.700073
current_received_ts = 82.699387
current_seq = 1357
count = 696
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.681114/1356, current 82.700073/1357: elapsed=18958.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.700073
last_received_ts = 82.699387
last_seq = 1357
current_ts = 82.719032
current_received_ts = 82.718346
current_seq = 1358
count = 697
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.700073/1357, current 82.719032/1358: elapsed=18957.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.719032
last_received_ts = 82.718346
last_seq = 1358
current_ts = 82.737991
current_received_ts = 82.737312
current_seq = 1359
count = 698
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.719032/1358, current 82.737991/1359: elapsed=18959.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.737991
last_received_ts = 82.737312
last_seq = 1359
current_ts = 82.756943
current_received_ts = 82.756264
current_seq = 1360
count = 699
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.737991/1359, current 82.756943/1360: elapsed=18957.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.756943
last_received_ts = 82.756264
last_seq = 1360
current_ts = 82.775902
current_received_ts = 82.775223
current_seq = 1361
count = 700
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.756943/1360, current 82.775902/1361: elapsed=18958.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.775902
last_received_ts = 82.775223
last_seq = 1361
current_ts = 82.794861
current_received_ts = 82.794174
current_seq = 1362
count = 701
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.775902/1361, current 82.794861/1362: elapsed=18957.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.794861
last_received_ts = 82.794174
last_seq = 1362
current_ts = 82.813820
current_received_ts = 82.813141
current_seq = 1363
count = 702
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.794861/1362, current 82.813820/1363: elapsed=18959.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.813820
last_received_ts = 82.813141
last_seq = 1363
current_ts = 82.832779
current_received_ts = 82.832092
current_seq = 1364
count = 703
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.813820/1363, current 82.832779/1364: elapsed=18957.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.832779
last_received_ts = 82.832092
last_seq = 1364
current_ts = 82.851730
current_received_ts = 82.851044
current_seq = 1365
count = 704
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.832779/1364, current 82.851730/1365: elapsed=18957.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.851730
last_received_ts = 82.851044
last_seq = 1365
current_ts = 82.870689
current_received_ts = 82.870026
current_seq = 1366
count = 705
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.851730/1365, current 82.870689/1366: elapsed=18958.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.870689
last_received_ts = 82.870026
last_seq = 1366
current_ts = 82.889648
current_received_ts = 82.888969
current_seq = 1367
count = 706
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.870689/1366, current 82.889648/1367: elapsed=18959.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.889648
last_received_ts = 82.888969
last_seq = 1367
current_ts = 82.908607
current_received_ts = 82.907928
current_seq = 1368
count = 707
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.889648/1367, current 82.908607/1368: elapsed=18960.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.908607
last_received_ts = 82.907928
last_seq = 1368
current_ts = 82.927567
current_received_ts = 82.926880
current_seq = 1369
count = 708
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.908607/1368, current 82.927567/1369: elapsed=18959.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.927567
last_received_ts = 82.926880
last_seq = 1369
current_ts = 82.946533
current_received_ts = 82.945847
current_seq = 1370
count = 709
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.927567/1369, current 82.946533/1370: elapsed=18961.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.946533
last_received_ts = 82.945847
last_seq = 1370
current_ts = 82.965492
current_received_ts = 82.964806
current_seq = 1371
count = 710
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.946533/1370, current 82.965492/1371: elapsed=18959.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.965492
last_received_ts = 82.964806
last_seq = 1371
current_ts = 82.984451
current_received_ts = 82.983757
current_seq = 1372
count = 711
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.965492/1371, current 82.984451/1372: elapsed=18959.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 82.984451
last_received_ts = 82.983757
last_seq = 1372
current_ts = 83.003410
current_received_ts = 83.002724
current_seq = 1373
count = 712
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 82.984451/1372, current 83.003410/1373: elapsed=18961.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.003410
last_received_ts = 83.002724
last_seq = 1373
current_ts = 83.022369
current_received_ts = 83.021683
current_seq = 1374
count = 713
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.003410/1373, current 83.022369/1374: elapsed=18959.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.022369
last_received_ts = 83.021683
last_seq = 1374
current_ts = 83.041328
current_received_ts = 83.040649
current_seq = 1375
count = 714
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.022369/1374, current 83.041328/1375: elapsed=18960.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.041328
last_received_ts = 83.040649
last_seq = 1375
current_ts = 83.060287
current_received_ts = 83.059608
current_seq = 1376
count = 715
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.041328/1375, current 83.060287/1376: elapsed=18960.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.060287
last_received_ts = 83.059608
last_seq = 1376
current_ts = 83.079247
current_received_ts = 83.078568
current_seq = 1377
count = 716
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.060287/1376, current 83.079247/1377: elapsed=18961.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.079247
last_received_ts = 83.078568
last_seq = 1377
current_ts = 83.098206
current_received_ts = 83.097527
current_seq = 1378
count = 717
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.079247/1377, current 83.098206/1378: elapsed=18958.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.098206
last_received_ts = 83.097527
last_seq = 1378
current_ts = 83.117165
current_received_ts = 83.116478
current_seq = 1379
count = 718
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.098206/1378, current 83.117165/1379: elapsed=18960.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.117165
last_received_ts = 83.116478
last_seq = 1379
current_ts = 83.136131
current_received_ts = 83.135445
current_seq = 1380
count = 719
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.117165/1379, current 83.136131/1380: elapsed=18960.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.136131
last_received_ts = 83.135445
last_seq = 1380
current_ts = 83.155090
current_received_ts = 83.154404
current_seq = 1381
count = 720
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.136131/1380, current 83.155090/1381: elapsed=18960.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.155090
last_received_ts = 83.154404
last_seq = 1381
current_ts = 83.174049
current_received_ts = 83.173363
current_seq = 1382
count = 721
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.155090/1381, current 83.174049/1382: elapsed=18959.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.174049
last_received_ts = 83.173363
last_seq = 1382
current_ts = 83.193008
current_received_ts = 83.192322
current_seq = 1383
count = 722
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.174049/1382, current 83.193008/1383: elapsed=18961.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.193008
last_received_ts = 83.192322
last_seq = 1383
current_ts = 83.211967
current_received_ts = 83.211281
current_seq = 1384
count = 723
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.193008/1383, current 83.211967/1384: elapsed=18959.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.211967
last_received_ts = 83.211281
last_seq = 1384
current_ts = 83.230927
current_received_ts = 83.230240
current_seq = 1385
count = 724
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.211967/1384, current 83.230927/1385: elapsed=18961.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.230927
last_received_ts = 83.230240
last_seq = 1385
current_ts = 83.249886
current_received_ts = 83.249199
current_seq = 1386
count = 725
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.230927/1385, current 83.249886/1386: elapsed=18959.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.249886
last_received_ts = 83.249199
last_seq = 1386
current_ts = 83.268845
current_received_ts = 83.268158
current_seq = 1387
count = 726
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.249886/1386, current 83.268845/1387: elapsed=18959.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.268845
last_received_ts = 83.268158
last_seq = 1387
current_ts = 83.287804
current_received_ts = 83.287117
current_seq = 1388
count = 727
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.268845/1387, current 83.287804/1388: elapsed=18960.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.287804
last_received_ts = 83.287117
last_seq = 1388
current_ts = 83.306763
current_received_ts = 83.306076
current_seq = 1389
count = 728
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.287804/1388, current 83.306763/1389: elapsed=18960.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.306763
last_received_ts = 83.306076
last_seq = 1389
current_ts = 83.325722
current_received_ts = 83.325043
current_seq = 1390
count = 729
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.306763/1389, current 83.325722/1390: elapsed=18959.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.325722
last_received_ts = 83.325043
last_seq = 1390
current_ts = 83.344688
current_received_ts = 83.344002
current_seq = 1391
count = 730
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.325722/1390, current 83.344688/1391: elapsed=18960.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.344688
last_received_ts = 83.344002
last_seq = 1391
current_ts = 83.363647
current_received_ts = 83.362961
current_seq = 1392
count = 731
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.344688/1391, current 83.363647/1392: elapsed=18960.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.363647
last_received_ts = 83.362961
last_seq = 1392
current_ts = 83.382607
current_received_ts = 83.381920
current_seq = 1393
count = 732
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.363647/1392, current 83.382607/1393: elapsed=18960.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.382607
last_received_ts = 83.381920
last_seq = 1393
current_ts = 83.401566
current_received_ts = 83.400871
current_seq = 1394
count = 733
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.382607/1393, current 83.401566/1394: elapsed=18959.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.401566
last_received_ts = 83.400871
last_seq = 1394
current_ts = 83.420525
current_received_ts = 83.419838
current_seq = 1395
count = 734
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.401566/1394, current 83.420525/1395: elapsed=18961.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.420525
last_received_ts = 83.419838
last_seq = 1395
current_ts = 83.439484
current_received_ts = 83.438797
current_seq = 1396
count = 735
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.420525/1395, current 83.439484/1396: elapsed=18959.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.439484
last_received_ts = 83.438797
last_seq = 1396
current_ts = 83.458443
current_received_ts = 83.457756
current_seq = 1397
count = 736
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.439484/1396, current 83.458443/1397: elapsed=18960.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.458443
last_received_ts = 83.457756
last_seq = 1397
current_ts = 83.477402
current_received_ts = 83.476715
current_seq = 1398
count = 737
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.458443/1397, current 83.477402/1398: elapsed=18960.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.477402
last_received_ts = 83.476715
last_seq = 1398
current_ts = 83.496361
current_received_ts = 83.495682
current_seq = 1399
count = 738
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.477402/1398, current 83.496361/1399: elapsed=18960.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.496361
last_received_ts = 83.495682
last_seq = 1399
current_ts = 83.515327
current_received_ts = 83.514641
current_seq = 1400
count = 739
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.496361/1399, current 83.515327/1400: elapsed=18960.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.515327
last_received_ts = 83.514641
last_seq = 1400
current_ts = 83.534286
current_received_ts = 83.533600
current_seq = 1401
count = 740
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.515327/1400, current 83.534286/1401: elapsed=18960.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.534286
last_received_ts = 83.533600
last_seq = 1401
current_ts = 83.553246
current_received_ts = 83.552551
current_seq = 1402
count = 741
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.534286/1401, current 83.553246/1402: elapsed=18958.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.553246
last_received_ts = 83.552551
last_seq = 1402
current_ts = 83.572205
current_received_ts = 83.571518
current_seq = 1403
count = 742
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.553246/1402, current 83.572205/1403: elapsed=18961.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.572205
last_received_ts = 83.571518
last_seq = 1403
current_ts = 83.591164
current_received_ts = 83.590477
current_seq = 1404
count = 743
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.572205/1403, current 83.591164/1404: elapsed=18959.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.591164
last_received_ts = 83.590477
last_seq = 1404
current_ts = 83.610123
current_received_ts = 83.609436
current_seq = 1405
count = 744
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.591164/1404, current 83.610123/1405: elapsed=18960.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.610123
last_received_ts = 83.609436
last_seq = 1405
current_ts = 83.629082
current_received_ts = 83.628395
current_seq = 1406
count = 745
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.610123/1405, current 83.629082/1406: elapsed=18960.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.629082
last_received_ts = 83.628395
last_seq = 1406
current_ts = 83.648041
current_received_ts = 83.647354
current_seq = 1407
count = 746
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.629082/1406, current 83.648041/1407: elapsed=18960.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.648041
last_received_ts = 83.647354
last_seq = 1407
current_ts = 83.667000
current_received_ts = 83.666313
current_seq = 1408
count = 747
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.648041/1407, current 83.667000/1408: elapsed=18960.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.667000
last_received_ts = 83.666313
last_seq = 1408
current_ts = 83.685959
current_received_ts = 83.685265
current_seq = 1409
count = 748
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.667000/1408, current 83.685959/1409: elapsed=18958.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.685959
last_received_ts = 83.685265
last_seq = 1409
current_ts = 83.704918
current_received_ts = 83.704231
current_seq = 1410
count = 749
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.685959/1409, current 83.704918/1410: elapsed=18961.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.704918
last_received_ts = 83.704231
last_seq = 1410
current_ts = 83.723877
current_received_ts = 83.723190
current_seq = 1411
count = 750
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.704918/1410, current 83.723877/1411: elapsed=18959.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.723877
last_received_ts = 83.723190
last_seq = 1411
current_ts = 83.742844
current_received_ts = 83.742157
current_seq = 1412
count = 751
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.723877/1411, current 83.742844/1412: elapsed=18961.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.742844
last_received_ts = 83.742157
last_seq = 1412
current_ts = 83.761795
current_received_ts = 83.761108
current_seq = 1413
count = 752
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.742844/1412, current 83.761795/1413: elapsed=18957.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.761795
last_received_ts = 83.761108
last_seq = 1413
current_ts = 83.780762
current_received_ts = 83.780075
current_seq = 1414
count = 753
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.761795/1413, current 83.780762/1414: elapsed=18966.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.780762
last_received_ts = 83.780075
last_seq = 1414
current_ts = 83.799721
current_received_ts = 83.799042
current_seq = 1415
count = 754
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.780762/1414, current 83.799721/1415: elapsed=18956.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.799721
last_received_ts = 83.799042
last_seq = 1415
current_ts = 83.818680
current_received_ts = 83.817993
current_seq = 1416
count = 755
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.799721/1415, current 83.818680/1416: elapsed=18960.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.818680
last_received_ts = 83.817993
last_seq = 1416
current_ts = 83.837639
current_received_ts = 83.836952
current_seq = 1417
count = 756
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.818680/1416, current 83.837639/1417: elapsed=18960.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.837639
last_received_ts = 83.836952
last_seq = 1417
current_ts = 83.856598
current_received_ts = 83.855911
current_seq = 1418
count = 757
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.837639/1417, current 83.856598/1418: elapsed=18959.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.856598
last_received_ts = 83.855911
last_seq = 1418
current_ts = 83.875557
current_received_ts = 83.874870
current_seq = 1419
count = 758
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.856598/1418, current 83.875557/1419: elapsed=18960.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.875557
last_received_ts = 83.874870
last_seq = 1419
current_ts = 83.894524
current_received_ts = 83.893837
current_seq = 1420
count = 759
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.875557/1419, current 83.894524/1420: elapsed=18961.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.894524
last_received_ts = 83.893837
last_seq = 1420
current_ts = 83.913483
current_received_ts = 83.912796
current_seq = 1421
count = 760
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.894524/1420, current 83.913483/1421: elapsed=18961.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.913483
last_received_ts = 83.912796
last_seq = 1421
current_ts = 83.932442
current_received_ts = 83.931755
current_seq = 1422
count = 761
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.913483/1421, current 83.932442/1422: elapsed=18961.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.932442
last_received_ts = 83.931755
last_seq = 1422
current_ts = 83.951401
current_received_ts = 83.950722
current_seq = 1423
count = 762
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.932442/1422, current 83.951401/1423: elapsed=18962.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.951401
last_received_ts = 83.950722
last_seq = 1423
current_ts = 83.970367
current_received_ts = 83.969681
current_seq = 1424
count = 763
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.951401/1423, current 83.970367/1424: elapsed=18961.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.970367
last_received_ts = 83.969681
last_seq = 1424
current_ts = 83.989326
current_received_ts = 83.988640
current_seq = 1425
count = 764
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.970367/1424, current 83.989326/1425: elapsed=18963.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 83.989326
last_received_ts = 83.988640
last_seq = 1425
current_ts = 84.008293
current_received_ts = 84.007599
current_seq = 1426
count = 765
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 83.989326/1425, current 84.008293/1426: elapsed=18962.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 84.008293
last_received_ts = 84.007599
last_seq = 1426
current_ts = 84.027252
current_received_ts = 84.026566
current_seq = 1427
count = 766
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 84.008293/1426, current 84.027252/1427: elapsed=18960.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 84.027252
last_received_ts = 84.026566
last_seq = 1427
current_ts = 84.046211
current_received_ts = 84.045525
current_seq = 1428
count = 767
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 84.027252/1427, current 84.046211/1428: elapsed=18961.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 84.046211
last_received_ts = 84.045525
last_seq = 1428
current_ts = 84.065170
current_received_ts = 84.064484
current_seq = 1429
count = 768
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 84.046211/1428, current 84.065170/1429: elapsed=18962.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 84.065170
last_received_ts = 84.064484
last_seq = 1429
current_ts = 84.084137
current_received_ts = 84.083450
current_seq = 1430
count = 769
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 84.065170/1429, current 84.084137/1430: elapsed=18962.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 84.084137
last_received_ts = 84.083450
last_seq = 1430
current_ts = 84.103096
current_received_ts = 84.102409
current_seq = 1431
count = 770
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 84.084137/1430, current 84.103096/1431: elapsed=18961.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 84.103096
last_received_ts = 84.102409
last_seq = 1431
current_ts = 84.122055
current_received_ts = 84.121368
current_seq = 1432
count = 771
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 84.103096/1431, current 84.122055/1432: elapsed=18962.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 84.122055
last_received_ts = 84.121368
last_seq = 1432
current_ts = 84.141022
current_received_ts = 84.140335
current_seq = 1433
count = 772
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 84.122055/1432, current 84.141022/1433: elapsed=18962.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 84.141022
last_received_ts = 84.140335
last_seq = 1433
current_ts = 84.159981
current_received_ts = 84.159294
current_seq = 1434
count = 773
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 84.141022/1433, current 84.159981/1434: elapsed=18961.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 84.159981
last_received_ts = 84.159294
last_seq = 1434
current_ts = 84.178940
current_received_ts = 84.178253
current_seq = 1435
count = 774
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 84.159981/1434, current 84.178940/1435: elapsed=18961.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 84.178940
last_received_ts = 84.178253
last_seq = 1435
current_ts = 84.197906
current_received_ts = 84.197220
current_seq = 1436
count = 775
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 84.178940/1435, current 84.197906/1436: elapsed=18962.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 84.197906
last_received_ts = 84.197220
last_seq = 1436
current_ts = 84.216866
current_received_ts = 84.216179
current_seq = 1437
count = 776
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 84.197906/1436, current 84.216866/1437: elapsed=18961.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 84.216866
last_received_ts = 84.216179
last_seq = 1437
current_ts = 84.235825
current_received_ts = 84.235138
current_seq = 1438
count = 777
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 84.216866/1437, current 84.235825/1438: elapsed=18962.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 84.235825
last_received_ts = 84.235138
last_seq = 1438
current_ts = 84.254791
current_received_ts = 84.254105
current_seq = 1439
count = 778
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 84.235825/1438, current 84.254791/1439: elapsed=18962.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 84.254791
last_received_ts = 84.254105
last_seq = 1439
current_ts = 84.273750
current_received_ts = 84.273064
current_seq = 1440
count = 779
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 84.254791/1439, current 84.273750/1440: elapsed=18961.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 84.273750
last_received_ts = 84.273064
last_seq = 1440
current_ts = 84.292709
current_received_ts = 84.292038
current_seq = 1441
count = 780
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 84.273750/1440, current 84.292709/1441: elapsed=18960.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 84.292709
last_received_ts = 84.292038
last_seq = 1441
current_ts = 84.311676
current_received_ts = 84.310989
current_seq = 1442
count = 781
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 84.292709/1441, current 84.311676/1442: elapsed=18963.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 84.311676
last_received_ts = 84.310989
last_seq = 1442
current_ts = 84.330635
current_received_ts = 84.329948
current_seq = 1443
count = 782
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 84.311676/1442, current 84.330635/1443: elapsed=18963.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 84.330635
last_received_ts = 84.329948
last_seq = 1443
current_ts = 84.349594
current_received_ts = 84.348907
current_seq = 1444
count = 783
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 84.330635/1443, current 84.349594/1444: elapsed=18958.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 84.349594
last_received_ts = 84.348907
last_seq = 1444
current_ts = 84.368561
current_received_ts = 84.367867
current_seq = 1445
count = 784
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 84.349594/1444, current 84.368561/1445: elapsed=18963.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 84.368561
last_received_ts = 84.367867
last_seq = 1445
current_ts = 84.387520
current_received_ts = 84.386833
current_seq = 1446
count = 785
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 84.368561/1445, current 84.387520/1446: elapsed=18963.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 84.387520
last_received_ts = 84.386833
last_seq = 1446
current_ts = 84.406479
current_received_ts = 84.405785
current_seq = 1447
count = 786
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 84.387520/1446, current 84.406479/1447: elapsed=18959.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 84.406479
last_received_ts = 84.405785
last_seq = 1447
current_ts = 84.425446
current_received_ts = 84.424751
current_seq = 1448
count = 787
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 84.406479/1447, current 84.425446/1448: elapsed=18963.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 84.425446
last_received_ts = 84.424751
last_seq = 1448
current_ts = 84.444405
current_received_ts = 84.443718
current_seq = 1449
count = 788
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 84.425446/1448, current 84.444405/1449: elapsed=18962.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 84.444405
last_received_ts = 84.443718
last_seq = 1449
current_ts = 84.463364
current_received_ts = 84.462685
current_seq = 1450
count = 789
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 84.444405/1449, current 84.463364/1450: elapsed=18961.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 84.463364
last_received_ts = 84.462685
last_seq = 1450
current_ts = 84.482330
current_received_ts = 84.481644
current_seq = 1451
count = 790
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 84.463364/1450, current 84.482330/1451: elapsed=18962.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 84.482330
last_received_ts = 84.481644
last_seq = 1451
current_ts = 84.501289
current_received_ts = 84.500603
current_seq = 1452
count = 791
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 84.482330/1451, current 84.501289/1452: elapsed=18962.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: name = flip
last_ts = 84.501289
last_received_ts = 84.500603
last_seq = 1452
current_ts = 84.520248
current_received_ts = 84.519569
current_seq = 1453
count = 792
seq_step = 1
(kms_flip:2083) DEBUG: flip ts/seq: last 84.501289/1452, current 84.520248/1453: elapsed=18961.0us expected=18882.9us +- 94.4us, error 0.4%
(kms_flip:2083) DEBUG: expected 795, counted 793, encoder type 2
(kms_flip:2083) DEBUG: dropped frames, expected 795, counted 793, encoder type 2
(kms_flip:2083) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1660:
(kms_flip:2083) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
(kms_flip:2083) igt_core-INFO: Stack trace:
(kms_flip:2083) igt_core-INFO:   #0 ../lib/igt_core.c:2055 __igt_fail_assert()
(kms_flip:2083) igt_core-INFO:   #1 ../tests/kms_flip.c:777 run_test_on_crtc_set.constprop.0()
(kms_flip:2083) igt_core-INFO:   #2 ../tests/kms_flip.c:1831 run_test()
(kms_flip:2083) igt_core-INFO:   #3 ../tests/kms_flip.c:2064 __igt_unique____real_main1987()
(kms_flip:2083) igt_core-INFO:   #4 ../tests/kms_flip.c:1987 main()
(kms_flip:2083) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2083) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2083) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-HDMI-A2: FAIL (18.727s)
Starting dynamic subtest: B-HDMI-A2
Dynamic subtest B-HDMI-A2: SUCCESS (15.520s)
Subtest plain-flip-ts-check: FAIL (34.251s)
Dmesg
<6> [69.069440] Console: switching to colour dummy device 80x25
<6> [69.069495] [IGT] kms_flip: executing
<7> [69.072020] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [69.072564] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [69.072741] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [69.075594] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:137:DP-1]
<7> [69.075610] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:137:DP-1]
<7> [69.079941] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:137:DP-1] disconnected
<7> [69.080227] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:148:HDMI-A-1]
<7> [69.080237] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:148:HDMI-A-1]
<7> [69.084963] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:148:HDMI-A-1] disconnected
<7> [69.085424] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:158:DP-2]
<7> [69.085434] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:158:DP-2]
<7> [69.090009] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:158:DP-2] disconnected
<7> [69.090342] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:164:HDMI-A-3]
<7> [69.090352] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:164:HDMI-A-3]
<7> [69.095050] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:164:HDMI-A-3] disconnected
<7> [69.095365] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:137:DP-1]
<7> [69.095376] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:137:DP-1]
<7> [69.100058] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:137:DP-1] disconnected
<7> [69.100079] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:148:HDMI-A-1]
<7> [69.100088] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:148:HDMI-A-1]
<7> [69.105060] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:148:HDMI-A-1] disconnected
<7> [69.105080] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:154:HDMI-A-2]
<7> [69.105090] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:154:HDMI-A-2]
<7> [69.131238] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:154:HDMI-A-2] Supported Monitor Refresh rate range is 0 Hz - 0 Hz
<7> [69.131255] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:154:HDMI-A-2] Assigning EDID-1.4 digital sink color depth as 8 bpc.
<7> [69.131260] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:154:HDMI-A-2] ELD monitor fitHeadless4k
<7> [69.131265] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:154:HDMI-A-2] ELD size 36, SAD count 0
<7> [69.131499] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus tc1] NAK for addr: 0040 w(1)
<7> [69.131741] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus tc1] NAK on first message, retry
<7> [69.132211] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus tc1] NAK for addr: 0040 w(1)
<7> [69.132455] i915 0000:00:02.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [69.133081] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "1366x768": 216 268000 1366 1414 1446 1526 768 771 777 814 0x40 0x6 (CLOCK_HIGH)
<7> [69.133093] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080i": 60 74250 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x15 (NO_INTERLACE)
<7> [69.133102] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080i": 60 74176 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x15 (NO_INTERLACE)
<7> [69.133112] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:154:HDMI-A-2] probed modes:
<7> [69.133130] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [69.133145] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "4088x2304": 10 108000 4088 4152 4280 4536 2304 2305 2308 2340 0x40 0x5
<7> [69.133161] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "3840x2160": 15 128430 3840 3889 3921 4000 2160 2163 2169 2195 0x40 0xa
<7> [69.133188] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "2880x1800": 11 60000 2880 2888 2984 3040 1800 1802 1820 1845 0x40 0xa
<7> [69.133203] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "2560x1600": 13 60000 2560 2568 2664 2720 1600 1602 1620 1645 0x40 0xa
<7> [69.133217] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "2560x1440": 15 60000 2560 2568 2664 2720 1440 1442 1460 1485 0x40 0xa
<7> [69.133230] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "2048x1536": 17 60000 2048 2056 2152 2208 1536 1538 1556 1581 0x40 0xa
<7> [69.133244] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1600x1200": 60 162000 1600 1664 1856 2160 1200 1201 1204 1250 0x40 0x5
<7> [69.133258] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1680x1050": 60 146250 1680 1784 1960 2240 1050 1053 1059 1089 0x40 0x6
<7> [69.133272] 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> [69.133286] 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> [69.133299] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1360x768": 60 84963 1366 1431 1567 1776 768 771 781 798 0x0 0x6
<7> [69.133313] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1360x768": 20 25190 1360 1368 1464 1520 768 770 788 813 0x40 0xa
<7> [69.133327] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1280x800": 60 83500 1280 1352 1480 1680 800 803 809 831 0x40 0x6
<7> [69.133340] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1152x864": 75 108000 1152 1216 1344 1600 864 865 868 900 0x40 0x5
<7> [69.133353] 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> [69.133367] 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> [69.133380] 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> [69.133393] 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> [69.133407] 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> [69.133420] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1440x480": 60 54054 1440 1472 1596 1716 480 489 495 525 0x40 0xa
<7> [69.133433] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1440x480": 60 54000 1440 1472 1596 1716 480 489 495 525 0x40 0xa
<7> [69.133446] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1024x576": 60 46560 1024 1064 1160 1296 576 579 584 599 0x0 0x6
<7> [69.133459] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "832x624": 75 57284 832 864 928 1152 624 625 628 667 0x40 0xa
<7> [69.133472] 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> [69.133485] 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> [69.133498] 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> [69.133511] 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> [69.133524] 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> [69.133537] 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> [69.133550] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "720x400": 70 28320 720 738 846 900 400 412 414 449 0x40 0x6
<7> [69.133602] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:158:DP-2]
<7> [69.133616] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:158:DP-2]
<7> [69.138221] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:158:DP-2] disconnected
<7> [69.138247] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:164:HDMI-A-3]
<7> [69.138259] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:164:HDMI-A-3]
<7> [69.143245] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:164:HDMI-A-3] disconnected
<6> [69.143722] [IGT] kms_flip: starting subtest plain-flip-ts-check
<6> [69.145335] [IGT] kms_flip: starting dynamic subtest A-HDMI-A2
<7> [69.145655] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:167]
<7> [69.145714] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:171]
<7> [69.208279] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:82:pipe A]
<7> [69.208795] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [69.209095] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [69.209363] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [69.209833] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [69.210113] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [69.210384] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [69.210671] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [69.210940] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2360, found 0)
<7> [69.211220] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [69.211495] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2360, found 0)
<7> [69.211787] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [69.212055] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2096, found 0)
<7> [69.212322] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [69.212624] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [69.212834] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [69.213043] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [69.213254] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [69.213470] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [69.213697] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [69.213907] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2360, found 0)
<7> [69.214118] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [69.214328] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2360, found 0)
<7> [69.214537] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [69.214762] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2096, found 0)
<7> [69.214979] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [69.215195] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [69.215406] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [69.215627] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [69.215841] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [69.216000] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [69.216164] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [69.216322] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [69.216481] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [69.216655] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [69.216819] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 139929, found 0)
<7> [69.216982] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 139929, found 0)
<7> [69.217148] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in port_clock (expected 139929, found 0)
<7> [69.217308] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset
<7> [69.217468] i915 0000:00:02.0: [drm:intel_unreference_shared_dpll_crtc [i915]] [CRTC:82:pipe A] releasing DPLL 0
<7> [69.217669] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [69.217829] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:82:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [69.218024] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] ddb ( 0 - 2016) -> ( 0 - 0), size 2016 -> 0
<7> [69.218190] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:77:cursor A] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0
<7> [69.218350] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [69.218508] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] lines 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [69.218676] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] blocks 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [69.218837] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] min_ddb 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [69.218977] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:82:pipe A] data rate 0 num active planes 0
<7> [69.219121] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 0
<7> [69.219281] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 180000 kHz, actual 180000 kHz
<7> [69.219426] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [69.219570] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:82:pipe A] enable: no [modeset]
<7> [69.219725] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:plane 1A] fb: [NOFB], visible: no
<7> [69.219866] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:41:plane 2A] fb: [NOFB], visible: no
<7> [69.220006] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:50:plane 3A] fb: [NOFB], visible: no
<7> [69.220146] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:59:plane 4A] fb: [NOFB], visible: no
<7> [69.220290] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:68:plane 5A] fb: [NOFB], visible: no
<7> [69.220429] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:77:cursor A] fb: [NOFB], visible: no
<7> [69.230840] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [69.249809] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_TC1
<7> [69.250792] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:82:pipe A]
<7> [69.251544] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disabling DPLL 0
<7> [69.252148] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:32:plane 1A]
<7> [69.252897] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:136:DDI B/PHY B]
<7> [69.253441] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:138:DP-MST A]
<7> [69.254019] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:139:DP-MST B]
<7> [69.254566] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:153:DDI TC1/PHY C]
<7> [69.255172] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:157:DDI TC2/PHY D]
<7> [69.255676] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:159:DP-MST A]
<7> [69.256157] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:160:DP-MST B]
<7> [69.256602] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:154:HDMI-A-2]
<7> [69.257321] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [69.257858] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [69.258461] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:82:pipe A]
<7> [69.260051] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:134:pipe B]
<7> [69.260453] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:82:pipe A]
<7> [69.260509] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:154:HDMI-A-2]
<7> [69.260649] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:154:HDMI-A-2] Limiting display bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [69.261047] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [69.261334] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [69.261638] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:153:DDI TC1/PHY C] [CRTC:82:pipe A]
<7> [69.261953] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [69.262256] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [69.262562] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [69.262889] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [69.263188] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [69.263486] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [69.263748] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [69.263986] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2360)
<7> [69.264204] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [69.264421] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2360)
<7> [69.264638] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [69.264872] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2096)
<7> [69.265096] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [69.265318] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [69.265536] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [69.265754] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [69.265993] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [69.266212] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [69.266428] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [69.266645] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2360)
<7> [69.266853] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [69.267004] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2360)
<7> [69.267159] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [69.267309] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2096)
<7> [69.267458] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [69.267608] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [69.267758] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [69.267920] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [69.268069] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [69.268223] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [69.268372] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [69.268521] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [69.268672] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [69.268831] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [69.268979] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 139929)
<7> [69.269128] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 139929)
<7> [69.269282] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in port_clock (expected 0, found 139929)
<7> [69.269437] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset
<7> [69.269610] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [69.269767] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:82:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x1
<7> [69.269945] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] ddb ( 0 - 0) -> ( 0 - 2016), size 0 -> 2016
<7> [69.270085] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:77:cursor A] ddb ( 0 - 0) -> (2016 - 2048), size 0 -> 32
<7> [69.270224] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [69.270362] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0
<7> [69.270501] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0
<7> [69.270639] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0
<7> [69.270781] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:82:pipe A] data rate 559716 num active planes 1
<7> [69.270939] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 560
<7> [69.271081] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [69.271235] i915 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [i915]] [PLANE:32:plane 1A] min cdclk (69965 kHz) > [CRTC:82:pipe A] min cdclk (0 kHz)
<7> [69.271378] i915 0000:00:02.0: [drm:intel_bw_calc_min_cdclk [i915]] new bandwidth min cdclk (10932 kHz) > old min cdclk (0 kHz)
<7> [69.271521] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 180000 kHz, actual 180000 kHz
<7> [69.271662] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [69.271803] i915 0000:00:02.0: [drm:intel_find_shared_dpll [i915]] [CRTC:82:pipe A] allocated DPLL 0
<7> [69.271962] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc [i915]] [CRTC:82:pipe A] reserving DPLL 0
<7> [69.272113] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:82:pipe A] enable: yes [modeset]
<7> [69.272256] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [69.272400] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [69.272542] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [69.272682] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [69.272822] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [69.272977] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [69.273121] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [69.273260] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [69.273399] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vblank delay: 0, framestart delay: 1, MSA timing delay: 0
<7> [69.273539] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0
<7> [69.273682] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: -1, vmax vblank: -2, vmin vtotal: 1, vmax vtotal: 0
<7> [69.273823] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [69.273978] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [69.274119] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139929, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [69.274265] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 53 139929 1920 2008 2096 2360 1080 1084 1089 1125 0x40 0x5
<7> [69.274406] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139929, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [69.274550] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 139929, pipe src: 1920x1080+0+0, pixel rate 139929
<7> [69.274692] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0
<7> [69.274832] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [69.274995] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [69.275141] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [69.275280] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1a6655d, cfgcr1: 0xe84, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [69.275419] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [69.275558] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [69.275697] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [69.275835] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [69.276001] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [69.276140] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [69.276278] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [69.276416] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [69.276559] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [69.276700] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [69.276840] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [69.276991] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [69.277130] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:plane 1A] fb: [FB:167] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [69.277270] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [69.277409] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [69.277549] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:41:plane 2A] fb: [NOFB], visible: no
<7> [69.277687] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:50:plane 3A] fb: [NOFB], visible: no
<7> [69.277825] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:59:plane 4A] fb: [NOFB], visible: no
<7> [69.277974] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:68:plane 5A] fb: [NOFB], visible: no
<7> [69.278113] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:77:cursor A] fb: [NOFB], visible: no
<7> [69.279391] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [69.279562] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:136:DDI B/PHY B]
<7> [69.279707] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:138:DP-MST A]
<7> [69.279848] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:139:DP-MST B]
<7> [69.280004] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:153:DDI TC1/PHY C]
<7> [69.280144] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:157:DDI TC2/PHY D]
<7> [69.280285] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:159:DP-MST A]
<7> [69.280425] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:160:DP-MST B]
<7> [69.280591] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [69.280775] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:82:pipe A]
<7> [69.280928] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling DPLL 0
<7> [69.281531] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_TC1
<7> [69.281713] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [69.282100] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [69.282251] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:32:plane 1A]
<7> [69.301127] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:154:HDMI-A-2]
<7> [69.301313] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:82:pipe A]
<6> [87.872668] [IGT] kms_flip: finished subtest A-HDMI-A2, FAIL
<6> [87.873779] [IGT] kms_flip: starting dynamic subtest B-HDMI-A2
<7> [87.874895] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:172]
<7> [87.875139] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:173]
<7> [87.926620] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:82:pipe A]
<7> [87.926788] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [87.927304] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [87.927710] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [87.928134] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [87.928530] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [87.928926] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [87.929354] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [87.929760] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2360, found 0)
<7> [87.930109] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [87.930435] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2360, found 0)
<7> [87.930760] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [87.931107] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2096, found 0)
<7> [87.931432] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [87.931758] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [87.932126] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [87.932459] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [87.932699] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [87.932839] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [87.932986] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [87.933127] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2360, found 0)
<7> [87.933266] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [87.933404] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2360, found 0)
<7> [87.933544] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [87.933683] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2096, found 0)
<7> [87.933822] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [87.933967] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [87.934105] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [87.934245] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [87.934384] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [87.934522] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [87.934660] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [87.934800] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [87.934938] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [87.935092] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [87.935231] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 139929, found 0)
<7> [87.935370] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 139929, found 0)
<7> [87.935525] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in port_clock (expected 139929, found 0)
<7> [87.935664] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset
<7> [87.935803] i915 0000:00:02.0: [drm:intel_unreference_shared_dpll_crtc [i915]] [CRTC:82:pipe A] releasing DPLL 0
<7> [87.935971] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [87.936113] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:82:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [87.936284] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] ddb ( 0 - 2016) -> ( 0 - 0), size 2016 -> 0
<7> [87.936423] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:77:cursor A] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0
<7> [87.936562] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [87.936699] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] lines 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [87.936837] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] blocks 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [87.936982] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] min_ddb 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [87.937124] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:82:pipe A] data rate 0 num active planes 0
<7> [87.937270] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 0
<7> [87.937428] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 180000 kHz, actual 180000 kHz
<7> [87.937569] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [87.937713] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:82:pipe A] enable: no [modeset]
<7> [87.937859] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:plane 1A] fb: [NOFB], visible: no
<7> [87.938010] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:41:plane 2A] fb: [NOFB], visible: no
<7> [87.938151] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:50:plane 3A] fb: [NOFB], visible: no
<7> [87.938292] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:59:plane 4A] fb: [NOFB], visible: no
<7> [87.938431] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:68:plane 5A] fb: [NOFB], visible: no
<7> [87.938571] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:77:cursor A] fb: [NOFB], visible: no
<7> [87.952310] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [87.973032] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_TC1
<7> [87.973621] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:82:pipe A]
<7> [87.974269] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disabling DPLL 0
<7> [87.974837] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:32:plane 1A]
<7> [87.975441] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:136:DDI B/PHY B]
<7> [87.975997] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:138:DP-MST A]
<7> [87.976537] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:139:DP-MST B]
<7> [87.977106] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:153:DDI TC1/PHY C]
<7> [87.977681] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:157:DDI TC2/PHY D]
<7> [87.978243] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:159:DP-MST A]
<7> [87.978768] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:160:DP-MST B]
<7> [87.979326] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:154:HDMI-A-2]
<7> [87.980035] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [87.980669] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [87.981284] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:82:pipe A]
<7> [87.982398] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:134:pipe B]
<7> [87.982820] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:134:pipe B]
<7> [87.982876] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:154:HDMI-A-2]
<7> [87.983034] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:154:HDMI-A-2] Limiting display bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [87.983487] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [87.983896] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:134:pipe B] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [87.984331] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:153:DDI TC1/PHY C] [CRTC:134:pipe B]
<7> [87.984722] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.enable (expected no, found yes)
<7> [87.985143] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.active (expected no, found yes)
<7> [87.985540] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in cpu_transcoder (expected -1, found 1)
<7> [87.985919] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in lane_count (expected 0, found 4)
<7> [87.986270] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [87.986586] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [87.986894] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [87.987201] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2360)
<7> [87.987480] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [87.987759] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2360)
<7> [87.988051] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [87.988331] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2096)
<7> [87.988630] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [87.988862] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [87.989106] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [87.989333] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [87.989561] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [87.989779] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [87.989986] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [87.990182] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2360)
<7> [87.990376] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [87.990572] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2360)
<7> [87.990767] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [87.990963] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2096)
<7> [87.991174] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [87.991371] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [87.991569] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [87.991760] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [87.991922] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [87.992093] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [87.992253] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [87.992412] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [87.992571] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [87.992736] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in dpll_hw_state
<7> [87.992877] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [87.993024] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: cfgcr0: 0x0, cfgcr1: 0x0, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [87.993165] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [87.993304] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: cfgcr0: 0x1a6655d, cfgcr1: 0xe84, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [87.993444] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [87.993584] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 139929)
<7> [87.993723] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 139929)
<7> [87.993863] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in port_clock (expected 0, found 139929)
<7> [87.994014] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:134:pipe B] fastset requirement not met, forcing full modeset
<7> [87.994172] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [87.994312] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:134:pipe B] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x2
<7> [87.994480] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] ddb ( 0 - 0) -> ( 0 - 2016), size 0 -> 2016
<7> [87.994619] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:129:cursor B] ddb ( 0 - 0) -> (2016 - 2048), size 0 -> 32
<7> [87.994757] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [87.994895] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0
<7> [87.995046] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0
<7> [87.995188] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0
<7> [87.995329] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:134:pipe B] data rate 559716 num active planes 1
<7> [87.995474] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 560
<7> [87.995618] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [87.995772] i915 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [i915]] [PLANE:84:plane 1B] min cdclk (69965 kHz) > [CRTC:134:pipe B] min cdclk (0 kHz)
<7> [87.995917] i915 0000:00:02.0: [drm:intel_bw_calc_min_cdclk [i915]] new bandwidth min cdclk (10932 kHz) > old min cdclk (0 kHz)
<7> [87.996071] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 180000 kHz, actual 180000 kHz
<7> [87.996212] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [87.996353] i915 0000:00:02.0: [drm:intel_find_shared_dpll [i915]] [CRTC:134:pipe B] allocated DPLL 0
<7> [87.996498] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc [i915]] [CRTC:134:pipe B] reserving DPLL 0
<7> [87.996642] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:134:pipe B] enable: yes [modeset]
<7> [87.996786] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [87.996927] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: B, pipe bpp: 24, dithering: 0
<7> [87.997078] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [87.997221] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [87.997364] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [87.997505] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [87.997645] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [87.997785] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [87.997923] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vblank delay: 0, framestart delay: 1, MSA timing delay: 0
<7> [87.998074] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0
<7> [87.998213] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: -1, vmax vblank: -2, vmin vtotal: 1, vmax vtotal: 0
<7> [87.998352] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [87.998491] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [87.998631] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139929, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [87.998771] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 53 139929 1920 2008 2096 2360 1080 1084 1089 1125 0x40 0x5
<7> [87.998909] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139929, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [87.999060] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 139929, pipe src: 1920x1080+0+0, pixel rate 139929
<7> [87.999199] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0
<7> [87.999337] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [87.999475] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [87.999613] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [87.999752] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1a6655d, cfgcr1: 0xe84, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [87.999891] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [88.000038] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [88.000175] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [88.000313] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [88.000451] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [88.000589] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [88.000729] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [88.000872] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [88.001021] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [88.001161] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [88.001301] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [88.001440] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [88.001579] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:84:plane 1B] fb: [FB:172] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [88.001719] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [88.001857] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [88.002004] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:93:plane 2B] fb: [NOFB], visible: no
<7> [88.002142] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:102:plane 3B] fb: [NOFB], visible: no
<7> [88.002281] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:111:plane 4B] fb: [NOFB], visible: no
<7> [88.002419] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:120:plane 5B] fb: [NOFB], visible: no
<7> [88.002557] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:129:cursor B] fb: [NOFB], visible: no
<7> [88.003141] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [88.003310] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:136:DDI B/PHY B]
<7> [88.003452] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:138:DP-MST A]
<7> [88.003592] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:139:DP-MST B]
<7> [88.003734] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:153:DDI TC1/PHY C]
<7> [88.003875] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:157:DDI TC2/PHY D]
<7> [88.004025] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:159:DP-MST A]
<7> [88.004164] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:160:DP-MST B]
<7> [88.004332] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [88.004509] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enable DPLL 0 (active 0x2, on? 0) for [CRTC:134:pipe B]
<7> [88.004654] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling DPLL 0
<7> [88.004910] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_TC1
<7> [88.005098] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe B
<7> [88.024890] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:154:HDMI-A-2]
<7> [88.025087] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:134:pipe B]
<7> [103.374797] i915 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:173] from all active usage due to RMFB ioctl
<7> [103.376305] i915 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:172] from all active usage due to RMFB ioctl
<7> [103.376934] i915 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:84:plane 1B] because [FB:172] is removed
<7> [103.377146] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] ddb ( 0 - 2016) -> ( 0 - 0), size 2016 -> 0
<7> [103.378044] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [103.378903] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] lines 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [103.379771] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] blocks 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [103.380544] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] min_ddb 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [103.380834] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:134:pipe B] data rate 0 num active planes 0
<7> [103.381055] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 0
<6> [103.394168] [IGT] kms_flip: finished subtest B-HDMI-A2, SUCCESS
<6> [103.394643] [IGT] kms_flip: finished subtest plain-flip-ts-check, FAIL
<6> [103.405765] [IGT] kms_flip: exiting, ret=98
<7> [103.406969] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [103.435507] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:154:HDMI-A-2] Limiting display bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [103.436125] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [103.436703] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [103.437295] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:153:DDI TC1/PHY C] [CRTC:82:pipe A]
<7> [103.437943] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [103.438533] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [103.439078] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [103.439661] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [103.440204] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [103.440781] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [103.441211] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [103.441687] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2360)
<7> [103.442113] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [103.442577] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2360)
<7> [103.442999] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [103.443437] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2096)
<7> [103.443851] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [103.444161] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [103.444487] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [103.444807] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [103.445128] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [103.445458] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [103.445771] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [103.446089] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2360)
<7> [103.446412] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [103.446722] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2360)
<7> [103.446973] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [103.447206] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2096)
<7> [103.447457] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [103.447693] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [103.447927] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [103.448163] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [103.448411] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [103.448646] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [103.448880] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [103.449115] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [103.449363] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [103.449608] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [103.449832] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 139929)
<7> [103.450003] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 139929)
<7> [103.450170] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in port_clock (expected 0, found 139929)
<7> [103.450344] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset
<7> [103.450510] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [103.450675] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [103.450838] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [103.451002] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [103.451165] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [103.451328] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [103.451506] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [103.451668] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2360, found 0)
<7> [103.451831] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [103.451993] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2360, found 0)
<7> [103.452157] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [103.452319] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2096, found 0)
<7> [103.452497] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [103.452660] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [103.452819] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [103.452959] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [103.453099] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [103.453239] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [103.453388] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [103.453527] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2360, found 0)
<7> [103.453667] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [103.453806] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2360, found 0)
<7> [103.453946] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [103.454085] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2096, found 0)
<7> [103.454224] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [103.454369] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [103.454509] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [103.454648] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [103.454787] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [103.454926] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [103.455065] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [103.455205] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [103.455349] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [103.455488] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [103.455626] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 139929, found 0)
<7> [103.455766] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 139929, found 0)
<7> [103.455904] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in port_clock (expected 139929, found 0)
<7> [103.456048] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:134:pipe B] fastset requirement not met, forcing full modeset
<7> [103.456189] i915 0000:00:02.0: [drm:intel_unreference_shared_dpll_crtc [i915]] [CRTC:134:pipe B] releasing DPLL 0
<7> [103.456365] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:82:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x2 -> 0x1
<7> [103.456506] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:134:pipe B] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x2 -> 0x1
<7> [103.456662] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] ddb ( 0 - 0) -> ( 0 - 2016), size 0 -> 2016
<7> [103.456803] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:77:cursor A] ddb ( 0 - 0) -> (2016 - 2048), size 0 -> 32
<7> [103.456943] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [103.457087] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0
<7> [103.457226] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0
<7> [103.457372] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0
<7> [103.457510] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:129:cursor B] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0
<7> [103.457649] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:82:pipe A] data rate 559716 num active planes 1
<7> [103.457793] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 560
<7> [103.457937] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [103.458093] i915 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [i915]] [PLANE:32:plane 1A] min cdclk (69965 kHz) > [CRTC:82:pipe A] min cdclk (0 kHz)
<7> [103.458239] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 180000 kHz, actual 180000 kHz
<7> [103.458389] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [103.458530] i915 0000:00:02.0: [drm:intel_find_shared_dpll [i915]] [CRTC:82:pipe A] allocated DPLL 0
<7> [103.458675] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc [i915]] [CRTC:82:pipe A] reserving DPLL 0
<7> [103.458821] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:82:pipe A] enable: yes [modeset]
<7> [103.458964] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [103.459106] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [103.459246] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [103.459396] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [103.459536] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [103.459676] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [103.459816] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [103.459959] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [103.460102] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vblank delay: 0, framestart delay: 1, MSA timing delay: 0
<7> [103.460244] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0
<7> [103.460394] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: -1, vmax vblank: -2, vmin vtotal: 1, vmax vtotal: 0
<7> [103.460534] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [103.460675] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [103.460815] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139929, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [103.460956] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 53 139929 1920 2008 2096 2360 1080 1084 1089 1125 0x40 0x5
<7> [103.461095] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139929, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [103.461235] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 139929, pipe src: 1920x1080+0+0, pixel rate 139929
<7> [103.461416] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0
<7> [103.461554] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [103.461694] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [103.461833] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [103.461974] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1a6655d, cfgcr1: 0xe84, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [103.462118] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [103.462258] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [103.462409] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [103.462549] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [103.462689] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [103.462828] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [103.462967] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [103.463106] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [103.463245] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [103.463393] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [103.463532] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [103.463670] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [103.463813] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:plane 1A] fb: [FB:168] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [103.463957] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [103.464099] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [103.464240] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:41:plane 2A] fb: [NOFB], visible: no
<7> [103.464388] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:50:plane 3A] fb: [NOFB], visible: no
<7> [103.464528] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:59:plane 4A] fb: [NOFB], visible: no
<7> [103.464683] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:68:plane 5A] fb: [NOFB], visible: no
<7> [103.464822] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:77:cursor A] fb: [NOFB], visible: no
<7> [103.464966] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:134:pipe B] enable: no [modeset]
<7> [103.465108] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:84:plane 1B] fb: [NOFB], visible: no
<7> [103.465249] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:93:plane 2B] fb: [NOFB], visible: no
<7> [103.465396] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:102:plane 3B] fb: [NOFB], visible: no
<7> [103.465537] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:111:plane 4B] fb: [NOFB], visible: no
<7> [103.465680] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:120:plane 5B] fb: [NOFB], visible: no
<7> [103.465823] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:129:cursor B] fb: [NOFB], visible: no
<7> [103.466338] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe B
<7> [103.471353] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_TC1
<7> [103.471611] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disable DPLL 0 (active 0x2, on? 1) for [CRTC:134:pipe B]
<7> [103.471822] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disabling DPLL 0
<7> [103.471974] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:136:DDI B/PHY B]
<7> [103.472116] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:138:DP-MST A]
<7> [103.472257] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:139:DP-MST B]
<7> [103.472409] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:153:DDI TC1/PHY C]
<7> [103.472551] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:157:DDI TC2/PHY D]
<7> [103.472692] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:159:DP-MST A]
<7> [103.472832] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:160:DP-MST B]
<7> [103.473051] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:82:pipe A]
<7> [103.473196] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling DPLL 0
<7> [103.473805] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_TC1
<7> [103.473990] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [103.474477] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [103.474627] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:32:plane 1A]
<7> [103.493513] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:154:HDMI-A-2]
<7> [103.493709] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:82:pipe A]
<7> [103.494114] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:134:pipe B]
<7> [103.494559] i915 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0
<6> [103.512570] Console: switching to colour frame buffer device 240x67
Created at 2025-03-13 05:30:44