Results for igt@kms_flip@wf_vblank-ts-check-interruptible@c-hdmi-a2

Result: Fail

integration-manifest git-log-oneline i915_display_info19 igt_runner19 runtimes19 results19.json results19-i915-load.json guc_logs19.tar i915_display_info_post_exec19 boot19 dmesg19

DetailValue
Duration 24.00 seconds
Hostname 
shard-rkl-7
Igt-Version 
IGT-Version: 2.4-g0a8f2f8f5 (x86_64) (Linux: 7.0.0-rc6-CI_DRM_18282-g7b7217a9e27a+ x86_64)
Out 
Starting dynamic subtest: C-HDMI-A2
  1920x1080: 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5 
Expected frametime: 18974us; measured 18973.4us +- 6.976us accuracy 0.11%
Event vblank: expected 532, counted 530, passrate = 77.36%, encoder type 2
Expected frametime: 18974us; measured 19169.6us +- 6.879us accuracy 0.11%
vblank interval differs from modeline! expected 18973.8us, measured 19170us +- 6.879us, difference 195.9us (28.5 sigma)
Event vblank: expected 527, counted 530, passrate = 52.83%, encoder type 2
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1891 run_test()
  #3 ../tests/kms_flip.c:2162 __igt_unique____real_main2047()
  #4 ../tests/kms_flip.c:2047 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest C-HDMI-A2: FAIL (24.002s)
Subtest wf_vblank-ts-check-interruptible: FAIL (45.053s)
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../lib/igt_kms.c:2352 kmstest_set_connector_dpms()
  #2 ../lib/igt_core.c:2961 igt_atexit_handler()
  #3 [erand48_r+0x1d6]
  #4 [exit+0x1e]
  #5 ../lib/igt_core.c:2416 igt_exit()
  #6 [main+0x3f]
  #7 [__libc_init_first+0x8a]
  #8 [__libc_start_main+0x8b]
  #9 [_start+0x25]
Err 
Starting dynamic subtest: C-HDMI-A2
(kms_flip:3013) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1711:
(kms_flip:3013) CRITICAL: Failed assertion: !retried
(kms_flip:3013) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest C-HDMI-A2 failed.
**** DEBUG ****
(kms_flip:3013) DEBUG: vblank ts/seq: last 219.421890/1158, current 219.611618/1168: elapsed=189730.0us expected=189734.4us +- 948.7us, error 0.0%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 219.611618
last_received_ts = 219.611023
last_seq = 1168
current_ts = 219.801346
current_received_ts = 219.800720
current_seq = 1178
count = 8
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 219.611618/1168, current 219.801346/1178: elapsed=189720.0us expected=189734.4us +- 948.7us, error 0.0%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 219.801346
last_received_ts = 219.800720
last_seq = 1178
current_ts = 219.991089
current_received_ts = 219.990479
current_seq = 1188
count = 9
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 219.801346/1178, current 219.991089/1188: elapsed=189740.0us expected=189734.4us +- 948.7us, error 0.0%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 219.991089
last_received_ts = 219.990479
last_seq = 1188
current_ts = 220.180817
current_received_ts = 220.180161
current_seq = 1198
count = 10
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 219.991089/1188, current 220.180817/1198: elapsed=189731.0us expected=189734.4us +- 948.7us, error 0.0%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 220.180817
last_received_ts = 220.180161
last_seq = 1198
current_ts = 220.370544
current_received_ts = 220.369919
current_seq = 1208
count = 11
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 220.180817/1198, current 220.370544/1208: elapsed=189729.0us expected=189734.4us +- 948.7us, error 0.0%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 220.370544
last_received_ts = 220.369919
last_seq = 1208
current_ts = 220.560257
current_received_ts = 220.559753
current_seq = 1218
count = 12
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 220.370544/1208, current 220.560257/1218: elapsed=189716.0us expected=189734.4us +- 948.7us, error 0.0%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 220.560257
last_received_ts = 220.559753
last_seq = 1218
current_ts = 220.749985
current_received_ts = 220.749390
current_seq = 1228
count = 13
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 220.560257/1218, current 220.749985/1228: elapsed=189732.0us expected=189734.4us +- 948.7us, error 0.0%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 220.749985
last_received_ts = 220.749390
last_seq = 1228
current_ts = 220.939728
current_received_ts = 220.939102
current_seq = 1238
count = 14
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 220.749985/1228, current 220.939728/1238: elapsed=189741.0us expected=189734.4us +- 948.7us, error 0.0%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 220.939728
last_received_ts = 220.939102
last_seq = 1238
current_ts = 221.129456
current_received_ts = 221.128845
current_seq = 1248
count = 15
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 220.939728/1238, current 221.129456/1248: elapsed=189730.0us expected=189734.4us +- 948.7us, error 0.0%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 221.129456
last_received_ts = 221.128845
last_seq = 1248
current_ts = 221.319183
current_received_ts = 221.318558
current_seq = 1258
count = 16
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 221.129456/1248, current 221.319183/1258: elapsed=189721.0us expected=189734.4us +- 948.7us, error 0.0%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 221.319183
last_received_ts = 221.318558
last_seq = 1258
current_ts = 221.508926
current_received_ts = 221.508270
current_seq = 1268
count = 17
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 221.319183/1258, current 221.508926/1268: elapsed=189736.0us expected=189734.4us +- 948.7us, error 0.0%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 221.508926
last_received_ts = 221.508270
last_seq = 1268
current_ts = 221.698654
current_received_ts = 221.698151
current_seq = 1278
count = 18
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 221.508926/1268, current 221.698654/1278: elapsed=189731.0us expected=189734.4us +- 948.7us, error 0.0%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 221.698654
last_received_ts = 221.698151
last_seq = 1278
current_ts = 221.888382
current_received_ts = 221.887756
current_seq = 1288
count = 19
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 221.698654/1278, current 221.888382/1288: elapsed=189728.0us expected=189734.4us +- 948.7us, error 0.0%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 221.888382
last_received_ts = 221.887756
last_seq = 1288
current_ts = 222.078110
current_received_ts = 222.077484
current_seq = 1298
count = 20
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 221.888382/1288, current 222.078110/1298: elapsed=189728.0us expected=189734.4us +- 948.7us, error 0.0%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 222.078110
last_received_ts = 222.077484
last_seq = 1298
current_ts = 222.267822
current_received_ts = 222.267197
current_seq = 1308
count = 21
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 222.078110/1298, current 222.267822/1308: elapsed=189721.0us expected=189734.4us +- 948.7us, error 0.0%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 222.267822
last_received_ts = 222.267197
last_seq = 1308
current_ts = 222.457565
current_received_ts = 222.456924
current_seq = 1318
count = 22
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 222.267822/1308, current 222.457565/1318: elapsed=189741.0us expected=189734.4us +- 948.7us, error 0.0%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 222.457565
last_received_ts = 222.456924
last_seq = 1318
current_ts = 222.647293
current_received_ts = 222.646713
current_seq = 1328
count = 23
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 222.457565/1318, current 222.647293/1328: elapsed=189729.0us expected=189734.4us +- 948.7us, error 0.0%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 222.647293
last_received_ts = 222.646713
last_seq = 1328
current_ts = 222.837021
current_received_ts = 222.836395
current_seq = 1338
count = 24
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 222.647293/1328, current 222.837021/1338: elapsed=189729.0us expected=189734.4us +- 948.7us, error 0.0%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 222.837021
last_received_ts = 222.836395
last_seq = 1338
current_ts = 223.026764
current_received_ts = 223.026123
current_seq = 1348
count = 25
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 222.837021/1338, current 223.026764/1348: elapsed=189731.0us expected=189734.4us +- 948.7us, error 0.0%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 223.026764
last_received_ts = 223.026123
last_seq = 1348
current_ts = 223.216492
current_received_ts = 223.215866
current_seq = 1358
count = 26
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 223.026764/1348, current 223.216492/1358: elapsed=189730.0us expected=189734.4us +- 948.7us, error 0.0%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 223.216492
last_received_ts = 223.215866
last_seq = 1358
current_ts = 223.406219
current_received_ts = 223.405594
current_seq = 1368
count = 27
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 223.216492/1358, current 223.406219/1368: elapsed=189730.0us expected=189734.4us +- 948.7us, error 0.0%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 223.406219
last_received_ts = 223.405594
last_seq = 1368
current_ts = 223.595932
current_received_ts = 223.595306
current_seq = 1378
count = 28
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 223.406219/1368, current 223.595932/1378: elapsed=189716.0us expected=189734.4us +- 948.7us, error 0.0%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 223.595932
last_received_ts = 223.595306
last_seq = 1378
current_ts = 223.785660
current_received_ts = 223.785065
current_seq = 1388
count = 29
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 223.595932/1378, current 223.785660/1388: elapsed=189733.0us expected=189734.4us +- 948.7us, error 0.0%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 223.785660
last_received_ts = 223.785065
last_seq = 1388
current_ts = 223.975403
current_received_ts = 223.974747
current_seq = 1398
count = 30
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 223.785660/1388, current 223.975403/1398: elapsed=189740.0us expected=189734.4us +- 948.7us, error 0.0%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 223.975403
last_received_ts = 223.974747
last_seq = 1398
current_ts = 224.165131
current_received_ts = 224.164474
current_seq = 1408
count = 31
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 223.975403/1398, current 224.165131/1408: elapsed=189725.0us expected=189734.4us +- 948.7us, error 0.0%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 224.165131
last_received_ts = 224.164474
last_seq = 1408
current_ts = 224.354858
current_received_ts = 224.354218
current_seq = 1418
count = 32
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 224.165131/1408, current 224.354858/1418: elapsed=189732.0us expected=189734.4us +- 948.7us, error 0.0%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 224.354858
last_received_ts = 224.354218
last_seq = 1418
current_ts = 224.544586
current_received_ts = 224.544113
current_seq = 1428
count = 33
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 224.354858/1418, current 224.544586/1428: elapsed=189723.0us expected=189734.4us +- 948.7us, error 0.0%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 224.544586
last_received_ts = 224.544113
last_seq = 1428
current_ts = 224.734329
current_received_ts = 224.733704
current_seq = 1438
count = 34
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 224.544586/1428, current 224.734329/1438: elapsed=189736.0us expected=189734.4us +- 948.7us, error 0.0%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 224.734329
last_received_ts = 224.733704
last_seq = 1438
current_ts = 224.924042
current_received_ts = 224.923538
current_seq = 1448
count = 35
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 224.734329/1438, current 224.924042/1448: elapsed=189717.0us expected=189734.4us +- 948.7us, error 0.0%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 224.924042
last_received_ts = 224.923538
last_seq = 1448
current_ts = 225.113770
current_received_ts = 225.113205
current_seq = 1458
count = 36
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 224.924042/1448, current 225.113770/1458: elapsed=189735.0us expected=189734.4us +- 948.7us, error 0.0%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 225.113770
last_received_ts = 225.113205
last_seq = 1458
current_ts = 225.303497
current_received_ts = 225.302902
current_seq = 1468
count = 37
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 225.113770/1458, current 225.303497/1468: elapsed=189725.0us expected=189734.4us +- 948.7us, error 0.0%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 225.303497
last_received_ts = 225.302902
last_seq = 1468
current_ts = 225.493317
current_received_ts = 225.492676
current_seq = 1478
count = 38
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 225.303497/1468, current 225.493317/1478: elapsed=189811.0us expected=189734.4us +- 948.7us, error 0.0%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 225.493317
last_received_ts = 225.492676
last_seq = 1478
current_ts = 225.683136
current_received_ts = 225.682510
current_seq = 1488
count = 39
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 225.493317/1478, current 225.683136/1488: elapsed=189830.0us expected=189734.4us +- 948.7us, error 0.1%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 225.683136
last_received_ts = 225.682510
last_seq = 1488
current_ts = 225.872971
current_received_ts = 225.872314
current_seq = 1498
count = 40
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 225.683136/1488, current 225.872971/1498: elapsed=189832.0us expected=189734.4us +- 948.7us, error 0.1%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 225.872971
last_received_ts = 225.872314
last_seq = 1498
current_ts = 226.063950
current_received_ts = 226.063354
current_seq = 1508
count = 41
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 225.872971/1498, current 226.063950/1508: elapsed=190978.0us expected=189734.4us +- 948.7us, error 0.7%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 225.872971/1498, current 226.063950/1508: elapsed=190978.0us expected=189734.4us
(kms_flip:3013) DEBUG: name = vblank
last_ts = 226.063950
last_received_ts = 226.063354
last_seq = 1508
current_ts = 226.257401
current_received_ts = 226.256790
current_seq = 1518
count = 42
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 226.063950/1508, current 226.257401/1518: elapsed=193455.0us expected=189734.4us +- 948.7us, error 2.0%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 226.063950/1508, current 226.257401/1518: elapsed=193455.0us expected=189734.4us
(kms_flip:3013) DEBUG: name = vblank
last_ts = 226.257401
last_received_ts = 226.256790
last_seq = 1518
current_ts = 226.450851
current_received_ts = 226.450256
current_seq = 1528
count = 43
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 226.257401/1518, current 226.450851/1528: elapsed=193450.0us expected=189734.4us +- 948.7us, error 2.0%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 226.257401/1518, current 226.450851/1528: elapsed=193450.0us expected=189734.4us
(kms_flip:3013) DEBUG: name = vblank
last_ts = 226.450851
last_received_ts = 226.450256
last_seq = 1528
current_ts = 226.644318
current_received_ts = 226.643723
current_seq = 1538
count = 44
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 226.450851/1528, current 226.644318/1538: elapsed=193457.0us expected=189734.4us +- 948.7us, error 2.0%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 226.450851/1528, current 226.644318/1538: elapsed=193457.0us expected=189734.4us
(kms_flip:3013) DEBUG: name = vblank
last_ts = 226.644318
last_received_ts = 226.643723
last_seq = 1538
current_ts = 226.837769
current_received_ts = 226.837173
current_seq = 1548
count = 45
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 226.644318/1538, current 226.837769/1548: elapsed=193451.0us expected=189734.4us +- 948.7us, error 2.0%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 226.644318/1538, current 226.837769/1548: elapsed=193451.0us expected=189734.4us
(kms_flip:3013) DEBUG: name = vblank
last_ts = 226.837769
last_received_ts = 226.837173
last_seq = 1548
current_ts = 227.031143
current_received_ts = 227.030533
current_seq = 1558
count = 46
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 226.837769/1548, current 227.031143/1558: elapsed=193386.0us expected=189734.4us +- 948.7us, error 1.9%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 226.837769/1548, current 227.031143/1558: elapsed=193386.0us expected=189734.4us
(kms_flip:3013) DEBUG: name = vblank
last_ts = 227.031143
last_received_ts = 227.030533
last_seq = 1558
current_ts = 227.224152
current_received_ts = 227.223557
current_seq = 1568
count = 47
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 227.031143/1558, current 227.224152/1568: elapsed=193004.0us expected=189734.4us +- 948.7us, error 1.7%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 227.031143/1558, current 227.224152/1568: elapsed=193004.0us expected=189734.4us
(kms_flip:3013) DEBUG: name = vblank
last_ts = 227.224152
last_received_ts = 227.223557
last_seq = 1568
current_ts = 227.417145
current_received_ts = 227.416504
current_seq = 1578
count = 48
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 227.224152/1568, current 227.417145/1578: elapsed=192996.0us expected=189734.4us +- 948.7us, error 1.7%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 227.224152/1568, current 227.417145/1578: elapsed=192996.0us expected=189734.4us
(kms_flip:3013) DEBUG: name = vblank
last_ts = 227.417145
last_received_ts = 227.416504
last_seq = 1578
current_ts = 227.610153
current_received_ts = 227.609528
current_seq = 1588
count = 49
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 227.417145/1578, current 227.610153/1588: elapsed=193005.0us expected=189734.4us +- 948.7us, error 1.7%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 227.417145/1578, current 227.610153/1588: elapsed=193005.0us expected=189734.4us
(kms_flip:3013) DEBUG: name = vblank
last_ts = 227.610153
last_received_ts = 227.609528
last_seq = 1588
current_ts = 227.803162
current_received_ts = 227.802536
current_seq = 1598
count = 50
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 227.610153/1588, current 227.803162/1598: elapsed=193000.0us expected=189734.4us +- 948.7us, error 1.7%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 227.610153/1588, current 227.803162/1598: elapsed=193000.0us expected=189734.4us
(kms_flip:3013) DEBUG: name = vblank
last_ts = 227.803162
last_received_ts = 227.802536
last_seq = 1598
current_ts = 227.996140
current_received_ts = 227.995544
current_seq = 1608
count = 51
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 227.803162/1598, current 227.996140/1608: elapsed=192985.0us expected=189734.4us +- 948.7us, error 1.7%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 227.803162/1598, current 227.996140/1608: elapsed=192985.0us expected=189734.4us
(kms_flip:3013) DEBUG: name = vblank
last_ts = 227.996140
last_received_ts = 227.995544
last_seq = 1608
current_ts = 228.188766
current_received_ts = 228.188141
current_seq = 1618
count = 52
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 227.996140/1608, current 228.188766/1618: elapsed=192632.0us expected=189734.4us +- 948.7us, error 1.5%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 227.996140/1608, current 228.188766/1618: elapsed=192632.0us expected=189734.4us
(kms_flip:3013) INFO: Event vblank: expected 532, counted 530, passrate = 77.36%, encoder type 2
(kms_flip:3013) DEBUG: dropped frames, expected 532, counted 530, passrate = 77.36%, encoder type 2
(kms_flip:3013) DEBUG: Retrying without a hotplug event
(kms_flip:3013) DEBUG: No stale events found
(kms_flip:3013) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:3013) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:3013) INFO: Expected frametime: 18974us; measured 19169.6us +- 6.879us accuracy 0.11%
(kms_flip:3013) INFO: vblank interval differs from modeline! expected 18973.8us, measured 19170us +- 6.879us, difference 195.9us (28.5 sigma)
(kms_flip:3013) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 231.801498
current_received_ts = 231.800903
current_seq = 1649
count = 0
seq_step = 10
(kms_flip:3013) DEBUG: name = vblank
last_ts = 231.801498
last_received_ts = 231.800903
last_seq = 1649
current_ts = 231.993164
current_received_ts = 231.992599
current_seq = 1659
count = 1
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 231.801498/1649, current 231.993164/1659: elapsed=191673.0us expected=191696.2us +- 958.5us, error 0.0%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 231.993164
last_received_ts = 231.992599
last_seq = 1659
current_ts = 232.184647
current_received_ts = 232.184052
current_seq = 1669
count = 2
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 231.993164/1659, current 232.184647/1669: elapsed=191485.0us expected=191696.2us +- 958.5us, error 0.1%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 232.184647
last_received_ts = 232.184052
last_seq = 1669
current_ts = 232.376099
current_received_ts = 232.375473
current_seq = 1679
count = 3
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 232.184647/1669, current 232.376099/1679: elapsed=191447.0us expected=191696.2us +- 958.5us, error 0.1%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 232.376099
last_received_ts = 232.375473
last_seq = 1679
current_ts = 232.567566
current_received_ts = 232.566940
current_seq = 1689
count = 4
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 232.376099/1679, current 232.567566/1689: elapsed=191466.0us expected=191696.2us +- 958.5us, error 0.1%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 232.567566
last_received_ts = 232.566940
last_seq = 1689
current_ts = 232.759003
current_received_ts = 232.758377
current_seq = 1699
count = 5
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 232.567566/1689, current 232.759003/1699: elapsed=191446.0us expected=191696.2us +- 958.5us, error 0.1%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 232.759003
last_received_ts = 232.758377
last_seq = 1699
current_ts = 232.950470
current_received_ts = 232.949860
current_seq = 1709
count = 6
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 232.759003/1699, current 232.950470/1709: elapsed=191469.0us expected=191696.2us +- 958.5us, error 0.1%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 232.950470
last_received_ts = 232.949860
last_seq = 1709
current_ts = 233.141785
current_received_ts = 233.141159
current_seq = 1719
count = 7
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 232.950470/1709, current 233.141785/1719: elapsed=191311.0us expected=191696.2us +- 958.5us, error 0.2%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 233.141785
last_received_ts = 233.141159
last_seq = 1719
current_ts = 233.333038
current_received_ts = 233.332458
current_seq = 1729
count = 8
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 233.141785/1719, current 233.333038/1729: elapsed=191251.0us expected=191696.2us +- 958.5us, error 0.2%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 233.333038
last_received_ts = 233.332458
last_seq = 1729
current_ts = 233.524292
current_received_ts = 233.523666
current_seq = 1739
count = 9
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 233.333038/1729, current 233.524292/1739: elapsed=191253.0us expected=191696.2us +- 958.5us, error 0.2%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 233.524292
last_received_ts = 233.523666
last_seq = 1739
current_ts = 233.715546
current_received_ts = 233.714966
current_seq = 1749
count = 10
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 233.524292/1739, current 233.715546/1749: elapsed=191260.0us expected=191696.2us +- 958.5us, error 0.2%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 233.715546
last_received_ts = 233.714966
last_seq = 1749
current_ts = 233.906799
current_received_ts = 233.906174
current_seq = 1759
count = 11
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 233.715546/1749, current 233.906799/1759: elapsed=191245.0us expected=191696.2us +- 958.5us, error 0.2%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 233.906799
last_received_ts = 233.906174
last_seq = 1759
current_ts = 234.097961
current_received_ts = 234.097321
current_seq = 1769
count = 12
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 233.906799/1759, current 234.097961/1769: elapsed=191172.0us expected=191696.2us +- 958.5us, error 0.3%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 234.097961
last_received_ts = 234.097321
last_seq = 1769
current_ts = 234.289032
current_received_ts = 234.288437
current_seq = 1779
count = 13
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 234.097961/1769, current 234.289032/1779: elapsed=191066.0us expected=191696.2us +- 958.5us, error 0.3%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 234.289032
last_received_ts = 234.288437
last_seq = 1779
current_ts = 234.480118
current_received_ts = 234.479477
current_seq = 1789
count = 14
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 234.289032/1779, current 234.480118/1789: elapsed=191087.0us expected=191696.2us +- 958.5us, error 0.3%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 234.480118
last_received_ts = 234.479477
last_seq = 1789
current_ts = 234.671188
current_received_ts = 234.670578
current_seq = 1799
count = 15
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 234.480118/1789, current 234.671188/1799: elapsed=191064.0us expected=191696.2us +- 958.5us, error 0.3%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 234.671188
last_received_ts = 234.670578
last_seq = 1799
current_ts = 234.862259
current_received_ts = 234.861664
current_seq = 1809
count = 16
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 234.671188/1799, current 234.862259/1809: elapsed=191078.0us expected=191696.2us +- 958.5us, error 0.3%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 234.862259
last_received_ts = 234.861664
last_seq = 1809
current_ts = 235.053299
current_received_ts = 235.052658
current_seq = 1819
count = 17
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 234.862259/1809, current 235.053299/1819: elapsed=191036.0us expected=191696.2us +- 958.5us, error 0.3%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 235.053299
last_received_ts = 235.052658
last_seq = 1819
current_ts = 235.244232
current_received_ts = 235.243622
current_seq = 1829
count = 18
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 235.053299/1819, current 235.244232/1829: elapsed=190935.0us expected=191696.2us +- 958.5us, error 0.4%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 235.244232
last_received_ts = 235.243622
last_seq = 1829
current_ts = 235.435150
current_received_ts = 235.434525
current_seq = 1839
count = 19
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 235.244232/1829, current 235.435150/1839: elapsed=190918.0us expected=191696.2us +- 958.5us, error 0.4%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 235.435150
last_received_ts = 235.434525
last_seq = 1839
current_ts = 235.626068
current_received_ts = 235.625488
current_seq = 1849
count = 20
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 235.435150/1839, current 235.626068/1849: elapsed=190916.0us expected=191696.2us +- 958.5us, error 0.4%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 235.626068
last_received_ts = 235.625488
last_seq = 1849
current_ts = 235.817001
current_received_ts = 235.816345
current_seq = 1859
count = 21
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 235.626068/1849, current 235.817001/1859: elapsed=190928.0us expected=191696.2us +- 958.5us, error 0.4%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 235.817001
last_received_ts = 235.816345
last_seq = 1859
current_ts = 236.007919
current_received_ts = 236.007385
current_seq = 1869
count = 22
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 235.817001/1859, current 236.007919/1869: elapsed=190915.0us expected=191696.2us +- 958.5us, error 0.4%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 236.007919
last_received_ts = 236.007385
last_seq = 1869
current_ts = 236.198700
current_received_ts = 236.198074
current_seq = 1879
count = 23
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 236.007919/1869, current 236.198700/1879: elapsed=190790.0us expected=191696.2us +- 958.5us, error 0.5%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 236.198700
last_received_ts = 236.198074
last_seq = 1879
current_ts = 236.389481
current_received_ts = 236.388870
current_seq = 1889
count = 24
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 236.198700/1879, current 236.389481/1889: elapsed=190784.0us expected=191696.2us +- 958.5us, error 0.5%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 236.389481
last_received_ts = 236.388870
last_seq = 1889
current_ts = 236.580276
current_received_ts = 236.579651
current_seq = 1899
count = 25
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 236.389481/1889, current 236.580276/1899: elapsed=190785.0us expected=191696.2us +- 958.5us, error 0.5%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 236.580276
last_received_ts = 236.579651
last_seq = 1899
current_ts = 236.771057
current_received_ts = 236.770523
current_seq = 1909
count = 26
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 236.580276/1899, current 236.771057/1909: elapsed=190784.0us expected=191696.2us +- 958.5us, error 0.5%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 236.771057
last_received_ts = 236.770523
last_seq = 1909
current_ts = 236.961838
current_received_ts = 236.961197
current_seq = 1919
count = 27
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 236.771057/1909, current 236.961838/1919: elapsed=190776.0us expected=191696.2us +- 958.5us, error 0.5%
(kms_flip:3013) DEBUG: name = vblank
last_ts = 236.961838
last_received_ts = 236.961197
last_seq = 1919
current_ts = 237.152527
current_received_ts = 237.151917
current_seq = 1929
count = 28
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 236.961838/1919, current 237.152527/1929: elapsed=190701.0us expected=191696.2us +- 958.5us, error 0.5%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 236.961838/1919, current 237.152527/1929: elapsed=190701.0us expected=191696.2us
(kms_flip:3013) DEBUG: name = vblank
last_ts = 237.152527
last_received_ts = 237.151917
last_seq = 1929
current_ts = 237.343201
current_received_ts = 237.342667
current_seq = 1939
count = 29
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 237.152527/1929, current 237.343201/1939: elapsed=190667.0us expected=191696.2us +- 958.5us, error 0.5%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 237.152527/1929, current 237.343201/1939: elapsed=190667.0us expected=191696.2us
(kms_flip:3013) DEBUG: name = vblank
last_ts = 237.343201
last_received_ts = 237.342667
last_seq = 1939
current_ts = 237.533859
current_received_ts = 237.533249
current_seq = 1949
count = 30
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 237.343201/1939, current 237.533859/1949: elapsed=190657.0us expected=191696.2us +- 958.5us, error 0.5%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 237.343201/1939, current 237.533859/1949: elapsed=190657.0us expected=191696.2us
(kms_flip:3013) DEBUG: name = vblank
last_ts = 237.533859
last_received_ts = 237.533249
last_seq = 1949
current_ts = 237.724533
current_received_ts = 237.723907
current_seq = 1959
count = 31
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 237.533859/1949, current 237.724533/1959: elapsed=190673.0us expected=191696.2us +- 958.5us, error 0.5%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 237.533859/1949, current 237.724533/1959: elapsed=190673.0us expected=191696.2us
(kms_flip:3013) DEBUG: name = vblank
last_ts = 237.724533
last_received_ts = 237.723907
last_seq = 1959
current_ts = 237.915192
current_received_ts = 237.914581
current_seq = 1969
count = 32
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 237.724533/1959, current 237.915192/1969: elapsed=190666.0us expected=191696.2us +- 958.5us, error 0.5%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 237.724533/1959, current 237.915192/1969: elapsed=190666.0us expected=191696.2us
(kms_flip:3013) DEBUG: name = vblank
last_ts = 237.915192
last_received_ts = 237.914581
last_seq = 1969
current_ts = 238.105804
current_received_ts = 238.105164
current_seq = 1979
count = 33
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 237.915192/1969, current 238.105804/1979: elapsed=190604.0us expected=191696.2us +- 958.5us, error 0.6%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 237.915192/1969, current 238.105804/1979: elapsed=190604.0us expected=191696.2us
(kms_flip:3013) DEBUG: name = vblank
last_ts = 238.105804
last_received_ts = 238.105164
last_seq = 1979
current_ts = 238.296371
current_received_ts = 238.295715
current_seq = 1989
count = 34
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 238.105804/1979, current 238.296371/1989: elapsed=190569.0us expected=191696.2us +- 958.5us, error 0.6%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 238.105804/1979, current 238.296371/1989: elapsed=190569.0us expected=191696.2us
(kms_flip:3013) DEBUG: name = vblank
last_ts = 238.296371
last_received_ts = 238.295715
last_seq = 1989
current_ts = 238.486923
current_received_ts = 238.486404
current_seq = 1999
count = 35
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 238.296371/1989, current 238.486923/1999: elapsed=190560.0us expected=191696.2us +- 958.5us, error 0.6%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 238.296371/1989, current 238.486923/1999: elapsed=190560.0us expected=191696.2us
(kms_flip:3013) DEBUG: name = vblank
last_ts = 238.486923
last_received_ts = 238.486404
last_seq = 1999
current_ts = 238.677490
current_received_ts = 238.676865
current_seq = 2009
count = 36
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 238.486923/1999, current 238.677490/2009: elapsed=190562.0us expected=191696.2us +- 958.5us, error 0.6%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 238.486923/1999, current 238.677490/2009: elapsed=190562.0us expected=191696.2us
(kms_flip:3013) DEBUG: name = vblank
last_ts = 238.677490
last_received_ts = 238.676865
last_seq = 2009
current_ts = 238.868042
current_received_ts = 238.867432
current_seq = 2019
count = 37
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 238.677490/2009, current 238.868042/2019: elapsed=190553.0us expected=191696.2us +- 958.5us, error 0.6%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 238.677490/2009, current 238.868042/2019: elapsed=190553.0us expected=191696.2us
(kms_flip:3013) DEBUG: name = vblank
last_ts = 238.868042
last_received_ts = 238.867432
last_seq = 2019
current_ts = 239.058578
current_received_ts = 239.058090
current_seq = 2029
count = 38
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 238.868042/2019, current 239.058578/2029: elapsed=190538.0us expected=191696.2us +- 958.5us, error 0.6%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 238.868042/2019, current 239.058578/2029: elapsed=190538.0us expected=191696.2us
(kms_flip:3013) DEBUG: name = vblank
last_ts = 239.058578
last_received_ts = 239.058090
last_seq = 2029
current_ts = 239.249054
current_received_ts = 239.248474
current_seq = 2039
count = 39
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 239.058578/2029, current 239.249054/2039: elapsed=190476.0us expected=191696.2us +- 958.5us, error 0.6%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 239.058578/2029, current 239.249054/2039: elapsed=190476.0us expected=191696.2us
(kms_flip:3013) DEBUG: name = vblank
last_ts = 239.249054
last_received_ts = 239.248474
last_seq = 2039
current_ts = 239.439529
current_received_ts = 239.439026
current_seq = 2049
count = 40
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 239.249054/2039, current 239.439529/2049: elapsed=190466.0us expected=191696.2us +- 958.5us, error 0.6%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 239.249054/2039, current 239.439529/2049: elapsed=190466.0us expected=191696.2us
(kms_flip:3013) DEBUG: name = vblank
last_ts = 239.439529
last_received_ts = 239.439026
last_seq = 2049
current_ts = 239.629990
current_received_ts = 239.629364
current_seq = 2059
count = 41
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 239.439529/2049, current 239.629990/2059: elapsed=190466.0us expected=191696.2us +- 958.5us, error 0.6%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 239.439529/2049, current 239.629990/2059: elapsed=190466.0us expected=191696.2us
(kms_flip:3013) DEBUG: name = vblank
last_ts = 239.629990
last_received_ts = 239.629364
last_seq = 2059
current_ts = 239.820465
current_received_ts = 239.819824
current_seq = 2069
count = 42
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 239.629990/2059, current 239.820465/2069: elapsed=190481.0us expected=191696.2us +- 958.5us, error 0.6%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 239.629990/2059, current 239.820465/2069: elapsed=190481.0us expected=191696.2us
(kms_flip:3013) DEBUG: name = vblank
last_ts = 239.820465
last_received_ts = 239.819824
last_seq = 2069
current_ts = 240.010925
current_received_ts = 240.010391
current_seq = 2079
count = 43
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 239.820465/2069, current 240.010925/2079: elapsed=190459.0us expected=191696.2us +- 958.5us, error 0.6%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 239.820465/2069, current 240.010925/2079: elapsed=190459.0us expected=191696.2us
(kms_flip:3013) DEBUG: name = vblank
last_ts = 240.010925
last_received_ts = 240.010391
last_seq = 2079
current_ts = 240.201324
current_received_ts = 240.200867
current_seq = 2089
count = 44
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 240.010925/2079, current 240.201324/2089: elapsed=190399.0us expected=191696.2us +- 958.5us, error 0.7%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 240.010925/2079, current 240.201324/2089: elapsed=190399.0us expected=191696.2us
(kms_flip:3013) DEBUG: name = vblank
last_ts = 240.201324
last_received_ts = 240.200867
last_seq = 2089
current_ts = 240.391724
current_received_ts = 240.391220
current_seq = 2099
count = 45
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 240.201324/2089, current 240.391724/2099: elapsed=190393.0us expected=191696.2us +- 958.5us, error 0.7%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 240.201324/2089, current 240.391724/2099: elapsed=190393.0us expected=191696.2us
(kms_flip:3013) DEBUG: name = vblank
last_ts = 240.391724
last_received_ts = 240.391220
last_seq = 2099
current_ts = 240.582108
current_received_ts = 240.581497
current_seq = 2109
count = 46
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 240.391724/2099, current 240.582108/2109: elapsed=190391.0us expected=191696.2us +- 958.5us, error 0.7%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 240.391724/2099, current 240.582108/2109: elapsed=190391.0us expected=191696.2us
(kms_flip:3013) DEBUG: name = vblank
last_ts = 240.582108
last_received_ts = 240.581497
last_seq = 2109
current_ts = 240.772491
current_received_ts = 240.771866
current_seq = 2119
count = 47
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 240.582108/2109, current 240.772491/2119: elapsed=190381.0us expected=191696.2us +- 958.5us, error 0.7%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 240.582108/2109, current 240.772491/2119: elapsed=190381.0us expected=191696.2us
(kms_flip:3013) DEBUG: name = vblank
last_ts = 240.772491
last_received_ts = 240.771866
last_seq = 2119
current_ts = 240.962891
current_received_ts = 240.962265
current_seq = 2129
count = 48
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 240.772491/2119, current 240.962891/2129: elapsed=190398.0us expected=191696.2us +- 958.5us, error 0.7%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 240.772491/2119, current 240.962891/2129: elapsed=190398.0us expected=191696.2us
(kms_flip:3013) DEBUG: name = vblank
last_ts = 240.962891
last_received_ts = 240.962265
last_seq = 2129
current_ts = 241.153229
current_received_ts = 241.152573
current_seq = 2139
count = 49
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 240.962891/2129, current 241.153229/2139: elapsed=190338.0us expected=191696.2us +- 958.5us, error 0.7%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 240.962891/2129, current 241.153229/2139: elapsed=190338.0us expected=191696.2us
(kms_flip:3013) DEBUG: name = vblank
last_ts = 241.153229
last_received_ts = 241.152573
last_seq = 2139
current_ts = 241.343536
current_received_ts = 241.342911
current_seq = 2149
count = 50
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 241.153229/2139, current 241.343536/2149: elapsed=190309.0us expected=191696.2us +- 958.5us, error 0.7%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 241.153229/2139, current 241.343536/2149: elapsed=190309.0us expected=191696.2us
(kms_flip:3013) DEBUG: name = vblank
last_ts = 241.343536
last_received_ts = 241.342911
last_seq = 2149
current_ts = 241.533859
current_received_ts = 241.533234
current_seq = 2159
count = 51
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 241.343536/2149, current 241.533859/2159: elapsed=190321.0us expected=191696.2us +- 958.5us, error 0.7%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 241.343536/2149, current 241.533859/2159: elapsed=190321.0us expected=191696.2us
(kms_flip:3013) DEBUG: name = vblank
last_ts = 241.533859
last_received_ts = 241.533234
last_seq = 2159
current_ts = 241.724197
current_received_ts = 241.723572
current_seq = 2169
count = 52
seq_step = 10
(kms_flip:3013) DEBUG: vblank ts/seq: last 241.533859/2159, current 241.724197/2169: elapsed=190333.0us expected=191696.2us +- 958.5us, error 0.7%
(kms_flip:3013) DEBUG: inconsistent vblank ts/seq: last 241.533859/2159, current 241.724197/2169: elapsed=190333.0us expected=191696.2us
(kms_flip:3013) INFO: Event vblank: expected 527, counted 530, passrate = 52.83%, encoder type 2
(kms_flip:3013) DEBUG: dropped frames, expected 527, counted 530, passrate = 52.83%, encoder type 2
(kms_flip:3013) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1711:
(kms_flip:3013) CRITICAL: Failed assertion: !retried
(kms_flip:3013) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:3013) igt_core-INFO: Stack trace:
(kms_flip:3013) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:3013) igt_core-INFO:   #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
(kms_flip:3013) igt_core-INFO:   #2 ../tests/kms_flip.c:1891 run_test()
(kms_flip:3013) igt_core-INFO:   #3 ../tests/kms_flip.c:2162 __igt_unique____real_main2047()
(kms_flip:3013) igt_core-INFO:   #4 ../tests/kms_flip.c:2047 main()
(kms_flip:3013) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:3013) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:3013) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest C-HDMI-A2: FAIL (24.002s)
Subtest wf_vblank-ts-check-interruptible: FAIL (45.053s)
(kms_flip:3013) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2352:
(kms_flip:3013) igt_kms-CRITICAL: Failed assertion: found_it
(kms_flip:3013) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor
(kms_flip:3013) igt_kms-CRITICAL: DPMS property not found on 400
Dmesg
<6> [277.928672] [IGT] kms_flip: starting dynamic subtest C-HDMI-A2
<7> [277.929779] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:418]
<7> [277.930108] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:419]
<7> [277.946194] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [277.946358] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe B]
<7> [277.946500] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [277.946689] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [277.946828] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [277.946962] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [277.947097] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [277.947228] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [277.947357] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [277.947501] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2360, found 0)
<7> [277.947660] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [277.947792] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2360, found 0)
<7> [277.947922] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [277.948050] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2096, found 0)
<7> [277.948181] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [277.948309] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [277.948449] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [277.948602] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [277.948730] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [277.948859] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [277.948987] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [277.949117] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2360, found 0)
<7> [277.949245] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [277.949374] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2360, found 0)
<7> [277.949517] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [277.949667] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2096, found 0)
<7> [277.949795] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [277.949923] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [277.950051] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [277.950178] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [277.950306] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [277.950497] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [277.950633] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [277.950762] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [277.950891] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [277.951020] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [277.951148] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 139929, found 0)
<7> [277.951277] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 139929, found 0)
<7> [277.951412] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in port_clock (expected 139929, found 0)
<7> [277.951563] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:269:pipe B] fastset requirement not met, forcing full modeset
<7> [277.951693] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:269:pipe B] releasing DPLL 0
<7> [277.951845] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [277.951975] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:269:pipe B] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [277.952125] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:263:cursor B] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0
<7> [277.952283] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:269:pipe B] min cdclk: 69965 kHz -> 0 kHz
<7> [277.952429] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:269:pipe B] enable: no [modeset]
<7> [277.952583] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:153:plane 1B] fb: [NOFB], visible: no
<7> [277.952716] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:183:plane 2B] fb: [NOFB], visible: no
<7> [277.952847] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:213:plane 3B] fb: [NOFB], visible: no
<7> [277.952977] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:243:plane 4B] fb: [NOFB], visible: no
<7> [277.953107] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:253:plane 5B] fb: [NOFB], visible: no
<7> [277.953237] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:263:cursor B] fb: [NOFB], visible: no
<7> [277.953463] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe B
<7> [277.964624] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_TC1
<7> [277.964787] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x2, on? 1) for [CRTC:269:pipe B]
<7> [277.964946] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [277.965090] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:389:DDI B/PHY B]
<7> [277.965226] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:399:DDI TC1/PHY C]
<7> [277.965378] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:403:DDI TC2/PHY D]
<7> [277.965535] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:405:DP-MST A]
<7> [277.965701] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:406:DP-MST B]
<7> [277.965831] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:407:DP-MST C]
<7> [277.965960] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:400:HDMI-A-2]
<7> [277.966154] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [277.966330] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [277.966519] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:269:pipe B]
<7> [277.966849] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]
<7> [277.966979] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]
<7> [277.967000] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:400:HDMI-A-2]
<7> [277.967054] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:400:HDMI-A-2] Limiting target display pipe bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [277.967196] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [277.967321] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:387:pipe C] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [277.967474] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:399:DDI TC1/PHY C] [CRTC:387:pipe C]
<7> [277.967621] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.enable (expected no, found yes)
<7> [277.967755] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.active (expected no, found yes)
<7> [277.967888] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2)
<7> [277.968021] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in lane_count (expected 0, found 4)
<7> [277.968154] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [277.968286] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [277.968424] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [277.968580] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2360)
<7> [277.968711] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [277.968842] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2360)
<7> [277.968972] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [277.969106] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2096)
<7> [277.969235] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [277.969364] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [277.969507] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [277.969660] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [277.969790] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [277.969919] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [277.970048] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [277.970177] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2360)
<7> [277.970307] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [277.970452] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2360)
<7> [277.970610] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [277.970742] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2096)
<7> [277.970872] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [277.971002] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [277.971131] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [277.971260] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [277.971390] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [277.971535] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [277.971687] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [277.971815] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [277.971944] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [277.972072] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [277.972202] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 139929)
<7> [277.972330] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 139929)
<7> [277.972471] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in port_clock (expected 0, found 139929)
<7> [277.972618] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:387:pipe C] fastset requirement not met, forcing full modeset
<7> [277.972767] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [277.972896] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:387:pipe C] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x4
<7> [277.973046] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:271:plane 1C] ddb ( 0 - 0) -> ( 0 - 2016), size 0 -> 2016
<7> [277.973172] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:381:cursor C] ddb ( 0 - 0) -> (2016 - 2048), size 0 -> 32
<7> [277.973298] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [277.973434] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0
<7> [277.973582] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0
<7> [277.973708] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] 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> [277.973847] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:387:pipe C] data rate 559716 num active planes 1
<7> [277.973979] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 560
<7> [277.974109] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [277.974249] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:387:pipe C] min cdclk: 0 kHz -> 69965 kHz
<7> [277.974394] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:387:pipe C] allocated DPLL 0
<7> [277.974546] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:387:pipe C] reserving DPLL 0
<7> [277.974712] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:387:pipe C] enable: yes [modeset]
<7> [277.974843] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [277.974975] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: C, pipe bpp: 24, dithering: 0
<7> [277.975107] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [277.975241] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [277.975373] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [277.975573] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [277.975704] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [277.975832] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [277.975960] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [277.976086] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 42, guardband: 44 vsync start: 0, vsync end: 0
<7> [277.976213] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1081, vmax vblank: 1081, vmin vtotal: 1125, vmax vtotal: 1125
<7> [277.976339] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [277.976480] 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> [277.976635] 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> [277.976763] 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> [277.976890] 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> [277.977018] 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> [277.977146] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 139929, pixel rate 139929, min cdclk 69965, min voltage level 0
<7> [277.977274] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0
<7> [277.977408] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [277.977555] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [277.977682] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [277.977809] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [277.977935] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [277.978061] 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> [277.978190] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [277.978315] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [277.978452] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [277.978598] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [277.978725] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [277.978851] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [277.978977] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [277.979104] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [277.979230] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [277.979356] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [277.979493] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [277.979645] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [277.979771] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:271:plane 1C] fb: [FB:418] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [277.979900] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [277.980027] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [277.980156] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:301:plane 2C] fb: [NOFB], visible: no
<7> [277.980282] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:331:plane 3C] fb: [NOFB], visible: no
<7> [277.980417] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:361:plane 4C] fb: [NOFB], visible: no
<7> [277.980560] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:371:plane 5C] fb: [NOFB], visible: no
<7> [277.980685] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:381:cursor C] fb: [NOFB], visible: no
<7> [277.981320] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [277.981490] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_4
<7> [277.981668] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:389:DDI B/PHY B]
<7> [277.981802] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:399:DDI TC1/PHY C]
<7> [277.981935] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:403:DDI TC2/PHY D]
<7> [277.982065] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:405:DP-MST A]
<7> [277.982195] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:406:DP-MST B]
<7> [277.982323] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:407:DP-MST C]
<7> [277.982526] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [277.982693] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x4, on? 0) for [CRTC:387:pipe C]
<7> [277.982829] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [277.983015] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_TC1
<7> [277.983186] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe C
<7> [278.003257] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:400:HDMI-A-2]
<7> [278.003504] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:387:pipe C]
<7> [291.368102] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [291.368673] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe B]
<7> [291.369214] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]
<7> [291.369475] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.enable (expected yes, found no)
<7> [291.370222] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.active (expected yes, found no)
<7> [291.370820] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in cpu_transcoder (expected 2, found -1)
<7> [291.371387] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in lane_count (expected 4, found 0)
<7> [291.371980] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [291.372539] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [291.373129] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [291.373594] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2360, found 0)
<7> [291.373742] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [291.373873] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2360, found 0)
<7> [291.374004] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [291.374133] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2096, found 0)
<7> [291.374263] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [291.374392] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [291.374521] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [291.374656] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [291.374784] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [291.374913] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [291.375041] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [291.375171] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2360, found 0)
<7> [291.375299] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [291.375428] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2360, found 0)
<7> [291.375556] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [291.375694] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2096, found 0)
<7> [291.375822] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [291.375950] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [291.376078] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [291.376208] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [291.376338] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [291.376467] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [291.376596] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [291.376736] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [291.376864] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [291.376993] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [291.377122] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 139929, found 0)
<7> [291.377250] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 139929, found 0)
<7> [291.377378] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in port_clock (expected 139929, found 0)
<7> [291.377507] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:387:pipe C] fastset requirement not met, forcing full modeset
<7> [291.377643] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:387:pipe C] releasing DPLL 0
<7> [291.377792] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [291.377923] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:387:pipe C] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x4 -> 0x0
<7> [291.378070] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:271:plane 1C] ddb ( 0 - 2016) -> ( 0 - 0), size 2016 -> 0
<7> [291.378199] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:381:cursor C] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0
<7> [291.378325] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [291.378452] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] lines 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [291.378582] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] blocks 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [291.378728] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] 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> [291.378870] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:387:pipe C] data rate 0 num active planes 0
<7> [291.379004] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 0
<7> [291.379152] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:387:pipe C] min cdclk: 69965 kHz -> 0 kHz
<7> [291.379300] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 8746 kHz -> 0 kHz
<7> [291.379435] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:387:pipe C] enable: no [modeset]
<7> [291.379569] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:271:plane 1C] fb: [NOFB], visible: no
<7> [291.379714] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:301:plane 2C] fb: [NOFB], visible: no
<7> [291.379846] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:331:plane 3C] fb: [NOFB], visible: no
<7> [291.379977] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:361:plane 4C] fb: [NOFB], visible: no
<7> [291.380106] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:371:plane 5C] fb: [NOFB], visible: no
<7> [291.380236] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:381:cursor C] fb: [NOFB], visible: no
<7> [291.380484] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe C
<7> [291.399462] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_TC1
<7> [291.399698] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x4, on? 1) for [CRTC:387:pipe C]
<7> [291.399854] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [291.399996] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:389:DDI B/PHY B]
<7> [291.400129] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:399:DDI TC1/PHY C]
<7> [291.400271] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:403:DDI TC2/PHY D]
<7> [291.400407] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:405:DP-MST A]
<7> [291.400546] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:406:DP-MST B]
<7> [291.400674] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:407:DP-MST C]
<7> [291.400802] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:400:HDMI-A-2]
<7> [291.400990] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [291.401162] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_4
<7> [291.401316] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [291.401502] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:387:pipe C]
<7> [291.401801] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]
<7> [291.401823] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:400:HDMI-A-2]
<7> [291.401875] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:400:HDMI-A-2] Limiting target display pipe bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [291.402015] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [291.402139] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:387:pipe C] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [291.402272] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:399:DDI TC1/PHY C] [CRTC:387:pipe C]
<7> [291.402410] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.enable (expected no, found yes)
<7> [291.402545] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.active (expected no, found yes)
<7> [291.402677] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2)
<7> [291.402808] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in lane_count (expected 0, found 4)
<7> [291.402942] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [291.403076] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [291.403210] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [291.403340] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2360)
<7> [291.403482] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [291.403613] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2360)
<7> [291.403743] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [291.403873] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2096)
<7> [291.404002] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [291.404131] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [291.404259] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [291.404395] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [291.404523] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [291.404651] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [291.404780] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [291.404908] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2360)
<7> [291.405036] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [291.405163] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2360)
<7> [291.405291] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [291.405431] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2096)
<7> [291.405559] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [291.405687] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [291.405815] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [291.405942] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [291.406069] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [291.406196] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [291.406330] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [291.406459] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [291.406588] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [291.406717] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [291.406846] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 139929)
<7> [291.406975] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 139929)
<7> [291.407103] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in port_clock (expected 0, found 139929)
<7> [291.407231] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:387:pipe C] fastset requirement not met, forcing full modeset
<7> [291.407387] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [291.407517] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:387:pipe C] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x4
<7> [291.407665] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:271:plane 1C] ddb ( 0 - 0) -> ( 0 - 2016), size 0 -> 2016
<7> [291.407791] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:381:cursor C] ddb ( 0 - 0) -> (2016 - 2048), size 0 -> 32
<7> [291.407917] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [291.408042] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0
<7> [291.408167] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0
<7> [291.408298] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] 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> [291.408435] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:387:pipe C] data rate 559716 num active planes 1
<7> [291.408569] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 560
<7> [291.408699] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [291.408840] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:387:pipe C] min cdclk: 0 kHz -> 69965 kHz
<7> [291.408984] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 8746 kHz
<7> [291.409114] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:387:pipe C] allocated DPLL 0
<7> [291.409249] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:387:pipe C] reserving DPLL 0
<7> [291.409395] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:387:pipe C] enable: yes [modeset]
<7> [291.409527] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [291.409660] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: C, pipe bpp: 24, dithering: 0
<7> [291.409791] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [291.409920] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [291.410050] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [291.410179] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [291.410315] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [291.410444] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [291.410572] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [291.410699] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 42, guardband: 44 vsync start: 0, vsync end: 0
<7> [291.410829] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1081, vmax vblank: 1081, vmin vtotal: 1125, vmax vtotal: 1125
<7> [291.410956] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [291.411086] 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> [291.411214] 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> [291.411381] 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> [291.411509] 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> [291.411638] 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> [291.411768] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 139929, pixel rate 139929, min cdclk 69965, min voltage level 0
<7> [291.411895] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0
<7> [291.412029] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [291.412160] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [291.412300] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [291.412429] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [291.412558] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [291.412687] 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> [291.412815] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [291.412943] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [291.413070] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [291.413197] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [291.413331] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [291.413459] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [291.413601] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [291.413728] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [291.413855] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [291.413982] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [291.414109] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [291.414240] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [291.414367] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:271:plane 1C] fb: [FB:418] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [291.414496] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [291.414622] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [291.414750] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:301:plane 2C] fb: [NOFB], visible: no
<7> [291.414877] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:331:plane 3C] fb: [NOFB], visible: no
<7> [291.415004] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:361:plane 4C] fb: [NOFB], visible: no
<7> [291.415130] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:371:plane 5C] fb: [NOFB], visible: no
<7> [291.415267] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:381:cursor C] fb: [NOFB], visible: no
<7> [291.415462] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [291.415608] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_4
<7> [291.415764] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:389:DDI B/PHY B]
<7> [291.415897] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:399:DDI TC1/PHY C]
<7> [291.416029] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:403:DDI TC2/PHY D]
<7> [291.416158] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:405:DP-MST A]
<7> [291.416299] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:406:DP-MST B]
<7> [291.416427] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:407:DP-MST C]
<7> [291.416590] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [291.416756] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x4, on? 0) for [CRTC:387:pipe C]
<7> [291.416891] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [291.417078] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_TC1
<7> [291.417258] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe C
<7> [291.437364] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:400:HDMI-A-2]
<7> [291.437599] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:387:pipe C]
<6> [301.931730] [IGT] kms_flip: finished subtest C-HDMI-A2, FAIL
<6> [301.935325] [IGT] kms_flip: finished subtest wf_vblank-ts-check-interruptible, FAIL
<7> [301.943287] i915 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:419] from all active usage due to RMFB ioctl
<7> [301.943844] i915 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:271:plane 1C] because [FB:419] is removed
<7> [301.943985] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:271:plane 1C] ddb ( 0 - 2016) -> ( 0 - 0), size 2016 -> 0
<7> [301.944483] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [301.944905] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] lines 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [301.945307] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] blocks 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [301.945754] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] 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> [301.946276] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:387:pipe C] data rate 0 num active planes 0
<7> [301.946707] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 0
<6> [301.970531] [IGT] kms_flip: exiting, ret=98
<7> [301.993745] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:400:HDMI-A-2] Limiting target display pipe bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [301.993929] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [301.994062] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [301.994214] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:399:DDI TC1/PHY C] [CRTC:151:pipe A]
<7> [301.994346] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [301.994488] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [301.994626] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [301.994779] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [301.994993] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [301.995198] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [301.995397] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [301.995594] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2360)
<7> [301.995923] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [301.996121] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2360)
<7> [301.996317] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [301.996513] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2096)
<7> [301.996757] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [301.996955] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [301.997152] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [301.997350] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [301.997546] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [301.997750] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [301.997947] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [301.998142] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2360)
<7> [301.998337] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [301.998531] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2360)
<7> [301.998733] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [301.998929] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2096)
<7> [301.999124] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [301.999317] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [301.999510] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [301.999712] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [301.999907] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [302.000104] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [302.000301] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [302.000494] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [302.000695] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [302.000892] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [302.001087] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 139929)
<7> [302.001283] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 139929)
<7> [302.001477] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 0, found 139929)
<7> [302.001677] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [302.001875] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.enable (expected yes, found no)
<7> [302.002070] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.active (expected yes, found no)
<7> [302.002264] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in cpu_transcoder (expected 2, found -1)
<7> [302.002456] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in lane_count (expected 4, found 0)
<7> [302.002652] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [302.003004] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [302.003202] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [302.003395] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2360, found 0)
<7> [302.003591] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [302.004859] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2360, found 0)
<7> [302.005063] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [302.005267] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2096, found 0)
<7> [302.005467] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [302.005670] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [302.005867] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [302.006064] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [302.006262] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [302.006457] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [302.006654] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [302.006878] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2360, found 0)
<7> [302.007074] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [302.007269] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2360, found 0)
<7> [302.007464] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [302.007670] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2096, found 0)
<7> [302.007865] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [302.008060] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [302.008257] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [302.008451] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [302.008643] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [302.008865] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [302.009059] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [302.009254] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [302.009449] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [302.009642] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [302.009859] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 139929, found 0)
<7> [302.010058] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 139929, found 0)
<7> [302.010252] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in port_clock (expected 139929, found 0)
<7> [302.010446] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:387:pipe C] fastset requirement not met, forcing full modeset
<7> [302.010641] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:387:pipe C] releasing DPLL 0
<7> [302.010911] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:151:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x4 -> 0x1
<7> [302.011103] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:387:pipe C] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x4 -> 0x1
<7> [302.011299] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 2016), size 0 -> 2016
<7> [302.011489] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:145:cursor A] ddb ( 0 - 0) -> (2016 - 2048), size 0 -> 32
<7> [302.011691] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35: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> [302.011882] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35: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> [302.012074] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35: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> [302.012267] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35: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> [302.012458] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:381:cursor C] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0
<7> [302.012676] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:151:pipe A] data rate 559716 num active planes 1
<7> [302.012878] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 560
<7> [302.013075] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [302.013296] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:151:pipe A] min cdclk: 0 kHz -> 69965 kHz
<7> [302.013492] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:387:pipe C] min cdclk: 69965 kHz -> 0 kHz
<7> [302.013726] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:151:pipe A] allocated DPLL 0
<7> [302.013930] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:151:pipe A] reserving DPLL 0
<7> [302.014137] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:151:pipe A] enable: yes [modeset]
<7> [302.014336] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [302.014534] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [302.014759] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [302.014956] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [302.015153] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [302.015346] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [302.015541] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [302.015753] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [302.015950] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [302.016144] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 42, guardband: 44 vsync start: 0, vsync end: 0
<7> [302.016341] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1081, vmax vblank: 1081, vmin vtotal: 1125, vmax vtotal: 1125
<7> [302.016534] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [302.016747] 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> [302.016945] 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> [302.017141] 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> [302.017335] 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> [302.017529] 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> [302.017739] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 139929, pixel rate 139929, min cdclk 69965, min voltage level 0
<7> [302.017933] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0
<7> [302.018128] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [302.018322] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [302.018518] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [302.018828] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [302.019022] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [302.019215] 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> [302.019410] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [302.019609] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [302.019827] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [302.020020] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [302.020212] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [302.020407] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [302.020600] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [302.020865] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [302.021060] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [302.021253] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [302.021445] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [302.021645] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [302.021843] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [FB:416] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [302.022041] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [302.022236] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [302.022434] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [302.022635] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [302.022829] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [302.023022] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [302.023216] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [302.023410] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:387:pipe C] enable: no [modeset]
<7> [302.023610] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:271:plane 1C] fb: [NOFB], visible: no
<7> [302.023803] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:301:plane 2C] fb: [NOFB], visible: no
<7> [302.023999] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:331:plane 3C] fb: [NOFB], visible: no
<7> [302.024192] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:361:plane 4C] fb: [NOFB], visible: no
<7> [302.024391] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:371:plane 5C] fb: [NOFB], visible: no
<7> [302.024582] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:381:cursor C] fb: [NOFB], visible: no
<7> [302.025042] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe C
<7> [302.043593] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_TC1
<7> [302.043756] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x4, on? 1) for [CRTC:387:pipe C]
<7> [302.043917] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [302.044064] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:389:DDI B/PHY B]
<7> [302.044201] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:399:DDI TC1/PHY C]
<7> [302.044334] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:403:DDI TC2/PHY D]
<7> [302.044466] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:405:DP-MST A]
<7> [302.044616] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:406:DP-MST B]
<7> [302.044816] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:407:DP-MST C]
<7> [302.045171] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:151:pipe A]
<7> [302.045376] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [302.045666] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_TC1
<7> [302.045922] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [302.046442] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [302.046660] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:35:plane 1A]
<7> [302.065674] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:400:HDMI-A-2]
<7> [302.065939] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:151:pipe A]
<7> [302.066617] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_4
<7> [302.066937] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:387:pipe C]
<7> [302.067774] i915 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0
Created at 2026-04-06 17:25:11