Results for igt@kms_flip@flip-vs-absolute-wf_vblank-interruptible@a-hdmi-a3

Result: Fail

integration-manifest git-log-oneline i915_display_info15 igt_runner15 runtimes15 results15.json results15-i915-load.json i915_display_info_post_exec15 boot15 dmesg15

DetailValue
Duration 19.47 seconds
Hostname 
shard-dg1-13
Igt-Version 
IGT-Version: 2.2-g815b131e6 (x86_64) (Linux: 6.18.0-rc4-Patchwork_155067v7-g0a7f6a2acbba+ x86_64)
Out 
Using IGT_SRANDOM=1762197129 for randomisation
Opened device: /dev/dri/card0
Using monotonic timestamps
Starting subtest: flip-vs-absolute-wf_vblank-interruptible
Starting dynamic subtest: A-HDMI-A3
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
Expected frametime: 16667us; measured 16666.9us +- 4.123us accuracy 0.07%
Event flip: expected 458, counted 470, passrate = 10.64%, encoder type 2
Event vblank: expected 458, counted 470, passrate = 8.51%, encoder type 2
Expected frametime: 16667us; measured 16499.4us +- 4.288us accuracy 0.08%
vblank interval differs from modeline! expected 16666.7us, measured 16499us +- 4.288us, difference 167.3us (39.0 sigma)
Event flip: expected 461, counted 460, passrate = 76.09%, encoder type 2
Event vblank: expected 461, counted 460, passrate = 73.91%, 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:1889 run_test()
  #3 ../tests/kms_flip.c:2158 __igt_unique____real_main2045()
  #4 ../tests/kms_flip.c:2045 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest A-HDMI-A3: FAIL (19.470s)
Err 
Starting subtest: flip-vs-absolute-wf_vblank-interruptible
Starting dynamic subtest: A-HDMI-A3
(kms_flip:2479) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1709:
(kms_flip:2479) CRITICAL: Failed assertion: !retried
(kms_flip:2479) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest A-HDMI-A3 failed.
**** DEBUG ****
(kms_flip:2479) DEBUG: name = flip
last_ts = 558.103882
last_received_ts = 558.103394
last_seq = 9807
current_ts = 558.267761
current_received_ts = 558.267273
current_seq = 9817
count = 43
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 558.103882/9807, current 558.267761/9817: elapsed=163862.0us expected=166669.4us +- 833.3us, error 1.7%
(kms_flip:2479) DEBUG: inconsistent flip ts/seq: last 558.103882/9807, current 558.267761/9817: elapsed=163862.0us expected=166669.4us
(kms_flip:2479) DEBUG: name = vblank
last_ts = 558.251343
last_received_ts = 558.250854
last_seq = 9816
current_ts = 558.415222
current_received_ts = 558.414734
current_seq = 9826
count = 43
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 558.251343/9816, current 558.415222/9826: elapsed=163871.0us expected=166669.4us +- 833.3us, error 1.7%
(kms_flip:2479) DEBUG: inconsistent vblank ts/seq: last 558.251343/9816, current 558.415222/9826: elapsed=163871.0us expected=166669.4us
(kms_flip:2479) DEBUG: name = flip
last_ts = 558.267761
last_received_ts = 558.267273
last_seq = 9817
current_ts = 558.431641
current_received_ts = 558.431152
current_seq = 9827
count = 44
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 558.267761/9817, current 558.431641/9827: elapsed=163889.0us expected=166669.4us +- 833.3us, error 1.7%
(kms_flip:2479) DEBUG: inconsistent flip ts/seq: last 558.267761/9817, current 558.431641/9827: elapsed=163889.0us expected=166669.4us
(kms_flip:2479) DEBUG: name = vblank
last_ts = 558.415222
last_received_ts = 558.414734
last_seq = 9826
current_ts = 558.579102
current_received_ts = 558.578613
current_seq = 9836
count = 44
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 558.415222/9826, current 558.579102/9836: elapsed=163871.0us expected=166669.4us +- 833.3us, error 1.7%
(kms_flip:2479) DEBUG: inconsistent vblank ts/seq: last 558.415222/9826, current 558.579102/9836: elapsed=163871.0us expected=166669.4us
(kms_flip:2479) DEBUG: name = flip
last_ts = 558.431641
last_received_ts = 558.431152
last_seq = 9827
current_ts = 558.595459
current_received_ts = 558.595093
current_seq = 9837
count = 45
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 558.431641/9827, current 558.595459/9837: elapsed=163862.0us expected=166669.4us +- 833.3us, error 1.7%
(kms_flip:2479) DEBUG: inconsistent flip ts/seq: last 558.431641/9827, current 558.595459/9837: elapsed=163862.0us expected=166669.4us
(kms_flip:2479) DEBUG: name = vblank
last_ts = 558.579102
last_received_ts = 558.578613
last_seq = 9836
current_ts = 558.742981
current_received_ts = 558.742493
current_seq = 9846
count = 45
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 558.579102/9836, current 558.742981/9846: elapsed=163871.0us expected=166669.4us +- 833.3us, error 1.7%
(kms_flip:2479) DEBUG: inconsistent vblank ts/seq: last 558.579102/9836, current 558.742981/9846: elapsed=163871.0us expected=166669.4us
(kms_flip:2479) DEBUG: name = flip
last_ts = 558.595459
last_received_ts = 558.595093
last_seq = 9837
current_ts = 558.759338
current_received_ts = 558.758911
current_seq = 9847
count = 46
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 558.595459/9837, current 558.759338/9847: elapsed=163873.0us expected=166669.4us +- 833.3us, error 1.7%
(kms_flip:2479) DEBUG: inconsistent flip ts/seq: last 558.595459/9837, current 558.759338/9847: elapsed=163873.0us expected=166669.4us
(kms_flip:2479) DEBUG: name = vblank
last_ts = 558.742981
last_received_ts = 558.742493
last_seq = 9846
current_ts = 558.906860
current_received_ts = 558.906433
current_seq = 9856
count = 46
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 558.742981/9846, current 558.906860/9856: elapsed=163870.0us expected=166669.4us +- 833.3us, error 1.7%
(kms_flip:2479) DEBUG: inconsistent vblank ts/seq: last 558.742981/9846, current 558.906860/9856: elapsed=163870.0us expected=166669.4us
(kms_flip:2479) INFO: Event flip: expected 458, counted 470, passrate = 10.64%, encoder type 2
(kms_flip:2479) DEBUG: dropped frames, expected 458, counted 470, passrate = 10.64%, encoder type 2
(kms_flip:2479) INFO: Event vblank: expected 458, counted 470, passrate = 8.51%, encoder type 2
(kms_flip:2479) DEBUG: dropped frames, expected 458, counted 470, passrate = 8.51%, encoder type 2
(kms_flip:2479) DEBUG: Retrying without a hotplug event
(kms_flip:2479) DEBUG: No stale events found
(kms_flip:2479) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:2479) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:2479) INFO: Expected frametime: 16667us; measured 16499.4us +- 4.288us accuracy 0.08%
(kms_flip:2479) INFO: vblank interval differs from modeline! expected 16666.7us, measured 16499us +- 4.288us, difference 167.3us (39.0 sigma)
(kms_flip:2479) DEBUG: name = flip
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 562.311951
current_received_ts = 562.311523
current_seq = 10059
count = 0
seq_step = 1
(kms_flip:2479) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 562.460449
current_received_ts = 562.460083
current_seq = 10068
count = 0
seq_step = 10
(kms_flip:2479) DEBUG: name = flip
last_ts = 562.311951
last_received_ts = 562.311523
last_seq = 10059
current_ts = 562.476929
current_received_ts = 562.476501
current_seq = 10069
count = 1
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 562.311951/10059, current 562.476929/10069: elapsed=164993.0us expected=164993.8us +- 825.0us, error 0.0%
(kms_flip:2479) DEBUG: name = vblank
last_ts = 562.460449
last_received_ts = 562.460083
last_seq = 10068
current_ts = 562.625427
current_received_ts = 562.625000
current_seq = 10078
count = 1
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 562.460449/10068, current 562.625427/10078: elapsed=164998.0us expected=164993.8us +- 825.0us, error 0.0%
(kms_flip:2479) DEBUG: name = flip
last_ts = 562.476929
last_received_ts = 562.476501
last_seq = 10069
current_ts = 562.641968
current_received_ts = 562.641479
current_seq = 10079
count = 2
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 562.476929/10069, current 562.641968/10079: elapsed=164993.0us expected=164993.8us +- 825.0us, error 0.0%
(kms_flip:2479) DEBUG: name = vblank
last_ts = 562.625427
last_received_ts = 562.625000
last_seq = 10078
current_ts = 562.790405
current_received_ts = 562.789978
current_seq = 10088
count = 2
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 562.625427/10078, current 562.790405/10088: elapsed=164993.0us expected=164993.8us +- 825.0us, error 0.0%
(kms_flip:2479) DEBUG: name = flip
last_ts = 562.641968
last_received_ts = 562.641479
last_seq = 10079
current_ts = 562.806946
current_received_ts = 562.806458
current_seq = 10089
count = 3
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 562.641968/10079, current 562.806946/10089: elapsed=164993.0us expected=164993.8us +- 825.0us, error 0.0%
(kms_flip:2479) DEBUG: name = vblank
last_ts = 562.790405
last_received_ts = 562.789978
last_seq = 10088
current_ts = 562.955444
current_received_ts = 562.954956
current_seq = 10098
count = 3
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 562.790405/10088, current 562.955444/10098: elapsed=165004.0us expected=164993.8us +- 825.0us, error 0.0%
(kms_flip:2479) DEBUG: name = flip
last_ts = 562.806946
last_received_ts = 562.806458
last_seq = 10089
current_ts = 562.971985
current_received_ts = 562.971497
current_seq = 10099
count = 4
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 562.806946/10089, current 562.971985/10099: elapsed=165024.0us expected=164993.8us +- 825.0us, error 0.0%
(kms_flip:2479) DEBUG: name = vblank
last_ts = 562.955444
last_received_ts = 562.954956
last_seq = 10098
current_ts = 563.120605
current_received_ts = 563.120178
current_seq = 10108
count = 4
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 562.955444/10098, current 563.120605/10108: elapsed=165191.0us expected=164993.8us +- 825.0us, error 0.1%
(kms_flip:2479) DEBUG: name = flip
last_ts = 562.971985
last_received_ts = 562.971497
last_seq = 10099
current_ts = 563.137146
current_received_ts = 563.136780
current_seq = 10109
count = 5
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 562.971985/10099, current 563.137146/10109: elapsed=165191.0us expected=164993.8us +- 825.0us, error 0.1%
(kms_flip:2479) DEBUG: name = vblank
last_ts = 563.120605
last_received_ts = 563.120178
last_seq = 10108
current_ts = 563.285828
current_received_ts = 563.285339
current_seq = 10118
count = 5
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 563.120605/10108, current 563.285828/10118: elapsed=165192.0us expected=164993.8us +- 825.0us, error 0.1%
(kms_flip:2479) DEBUG: name = flip
last_ts = 563.137146
last_received_ts = 563.136780
last_seq = 10109
current_ts = 563.302368
current_received_ts = 563.301880
current_seq = 10119
count = 6
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 563.137146/10109, current 563.302368/10119: elapsed=165189.0us expected=164993.8us +- 825.0us, error 0.1%
(kms_flip:2479) DEBUG: name = vblank
last_ts = 563.285828
last_received_ts = 563.285339
last_seq = 10118
current_ts = 563.450989
current_received_ts = 563.450562
current_seq = 10128
count = 6
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 563.285828/10118, current 563.450989/10128: elapsed=165191.0us expected=164993.8us +- 825.0us, error 0.1%
(kms_flip:2479) DEBUG: name = flip
last_ts = 563.302368
last_received_ts = 563.301880
last_seq = 10119
current_ts = 563.467529
current_received_ts = 563.467163
current_seq = 10129
count = 7
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 563.302368/10119, current 563.467529/10129: elapsed=165194.0us expected=164993.8us +- 825.0us, error 0.1%
(kms_flip:2479) DEBUG: name = vblank
last_ts = 563.450989
last_received_ts = 563.450562
last_seq = 10128
current_ts = 563.616211
current_received_ts = 563.615723
current_seq = 10138
count = 7
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 563.450989/10128, current 563.616211/10138: elapsed=165191.0us expected=164993.8us +- 825.0us, error 0.1%
(kms_flip:2479) DEBUG: name = flip
last_ts = 563.467529
last_received_ts = 563.467163
last_seq = 10129
current_ts = 563.632751
current_received_ts = 563.632263
current_seq = 10139
count = 8
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 563.467529/10129, current 563.632751/10139: elapsed=165189.0us expected=164993.8us +- 825.0us, error 0.1%
(kms_flip:2479) DEBUG: name = vblank
last_ts = 563.616211
last_received_ts = 563.615723
last_seq = 10138
current_ts = 563.781372
current_received_ts = 563.780945
current_seq = 10148
count = 8
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 563.616211/10138, current 563.781372/10148: elapsed=165195.0us expected=164993.8us +- 825.0us, error 0.1%
(kms_flip:2479) DEBUG: name = flip
last_ts = 563.632751
last_received_ts = 563.632263
last_seq = 10139
current_ts = 563.797913
current_received_ts = 563.797485
current_seq = 10149
count = 9
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 563.632751/10139, current 563.797913/10149: elapsed=165194.0us expected=164993.8us +- 825.0us, error 0.1%
(kms_flip:2479) DEBUG: name = vblank
last_ts = 563.781372
last_received_ts = 563.780945
last_seq = 10148
current_ts = 563.946594
current_received_ts = 563.946106
current_seq = 10158
count = 9
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 563.781372/10148, current 563.946594/10158: elapsed=165193.0us expected=164993.8us +- 825.0us, error 0.1%
(kms_flip:2479) DEBUG: name = flip
last_ts = 563.797913
last_received_ts = 563.797485
last_seq = 10149
current_ts = 563.963135
current_received_ts = 563.962708
current_seq = 10159
count = 10
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 563.797913/10149, current 563.963135/10159: elapsed=165210.0us expected=164993.8us +- 825.0us, error 0.1%
(kms_flip:2479) DEBUG: name = vblank
last_ts = 563.946594
last_received_ts = 563.946106
last_seq = 10158
current_ts = 564.111938
current_received_ts = 564.111511
current_seq = 10168
count = 10
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 563.946594/10158, current 564.111938/10168: elapsed=165365.0us expected=164993.8us +- 825.0us, error 0.2%
(kms_flip:2479) DEBUG: name = flip
last_ts = 563.963135
last_received_ts = 563.962708
last_seq = 10159
current_ts = 564.128479
current_received_ts = 564.128113
current_seq = 10169
count = 11
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 563.963135/10159, current 564.128479/10169: elapsed=165365.0us expected=164993.8us +- 825.0us, error 0.2%
(kms_flip:2479) DEBUG: name = vblank
last_ts = 564.111938
last_received_ts = 564.111511
last_seq = 10168
current_ts = 564.277344
current_received_ts = 564.276855
current_seq = 10178
count = 11
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 564.111938/10168, current 564.277344/10178: elapsed=165361.0us expected=164993.8us +- 825.0us, error 0.2%
(kms_flip:2479) DEBUG: name = flip
last_ts = 564.128479
last_received_ts = 564.128113
last_seq = 10169
current_ts = 564.293823
current_received_ts = 564.293396
current_seq = 10179
count = 12
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 564.128479/10169, current 564.293823/10179: elapsed=165363.0us expected=164993.8us +- 825.0us, error 0.2%
(kms_flip:2479) DEBUG: name = vblank
last_ts = 564.277344
last_received_ts = 564.276855
last_seq = 10178
current_ts = 564.442688
current_received_ts = 564.442200
current_seq = 10188
count = 12
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 564.277344/10178, current 564.442688/10188: elapsed=165359.0us expected=164993.8us +- 825.0us, error 0.2%
(kms_flip:2479) DEBUG: name = flip
last_ts = 564.293823
last_received_ts = 564.293396
last_seq = 10179
current_ts = 564.459229
current_received_ts = 564.458740
current_seq = 10189
count = 13
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 564.293823/10179, current 564.459229/10189: elapsed=165371.0us expected=164993.8us +- 825.0us, error 0.2%
(kms_flip:2479) DEBUG: name = vblank
last_ts = 564.442688
last_received_ts = 564.442200
last_seq = 10188
current_ts = 564.608032
current_received_ts = 564.607544
current_seq = 10198
count = 13
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 564.442688/10188, current 564.608032/10198: elapsed=165371.0us expected=164993.8us +- 825.0us, error 0.2%
(kms_flip:2479) DEBUG: name = flip
last_ts = 564.459229
last_received_ts = 564.458740
last_seq = 10189
current_ts = 564.624573
current_received_ts = 564.624146
current_seq = 10199
count = 14
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 564.459229/10189, current 564.624573/10199: elapsed=165361.0us expected=164993.8us +- 825.0us, error 0.2%
(kms_flip:2479) DEBUG: name = vblank
last_ts = 564.608032
last_received_ts = 564.607544
last_seq = 10198
current_ts = 564.773438
current_received_ts = 564.772949
current_seq = 10208
count = 14
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 564.608032/10198, current 564.773438/10208: elapsed=165365.0us expected=164993.8us +- 825.0us, error 0.2%
(kms_flip:2479) DEBUG: name = flip
last_ts = 564.624573
last_received_ts = 564.624146
last_seq = 10199
current_ts = 564.789978
current_received_ts = 564.789490
current_seq = 10209
count = 15
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 564.624573/10199, current 564.789978/10209: elapsed=165365.0us expected=164993.8us +- 825.0us, error 0.2%
(kms_flip:2479) DEBUG: name = vblank
last_ts = 564.773438
last_received_ts = 564.772949
last_seq = 10208
current_ts = 564.938782
current_received_ts = 564.938293
current_seq = 10218
count = 15
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 564.773438/10208, current 564.938782/10218: elapsed=165359.0us expected=164993.8us +- 825.0us, error 0.2%
(kms_flip:2479) DEBUG: name = flip
last_ts = 564.789978
last_received_ts = 564.789490
last_seq = 10209
current_ts = 564.955322
current_received_ts = 564.954895
current_seq = 10219
count = 16
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 564.789978/10209, current 564.955322/10219: elapsed=165374.0us expected=164993.8us +- 825.0us, error 0.2%
(kms_flip:2479) DEBUG: name = vblank
last_ts = 564.938782
last_received_ts = 564.938293
last_seq = 10218
current_ts = 565.104309
current_received_ts = 565.103821
current_seq = 10228
count = 16
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 564.938782/10218, current 565.104309/10228: elapsed=165519.0us expected=164993.8us +- 825.0us, error 0.3%
(kms_flip:2479) DEBUG: name = flip
last_ts = 564.955322
last_received_ts = 564.954895
last_seq = 10219
current_ts = 565.120850
current_received_ts = 565.120422
current_seq = 10229
count = 17
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 564.955322/10219, current 565.120850/10229: elapsed=165515.0us expected=164993.8us +- 825.0us, error 0.3%
(kms_flip:2479) DEBUG: name = vblank
last_ts = 565.104309
last_received_ts = 565.103821
last_seq = 10228
current_ts = 565.269836
current_received_ts = 565.269348
current_seq = 10238
count = 17
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 565.104309/10228, current 565.269836/10238: elapsed=165519.0us expected=164993.8us +- 825.0us, error 0.3%
(kms_flip:2479) DEBUG: name = flip
last_ts = 565.120850
last_received_ts = 565.120422
last_seq = 10229
current_ts = 565.286377
current_received_ts = 565.285889
current_seq = 10239
count = 18
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 565.120850/10229, current 565.286377/10239: elapsed=165519.0us expected=164993.8us +- 825.0us, error 0.3%
(kms_flip:2479) DEBUG: name = vblank
last_ts = 565.269836
last_received_ts = 565.269348
last_seq = 10238
current_ts = 565.435303
current_received_ts = 565.434875
current_seq = 10248
count = 18
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 565.269836/10238, current 565.435303/10248: elapsed=165514.0us expected=164993.8us +- 825.0us, error 0.3%
(kms_flip:2479) DEBUG: name = flip
last_ts = 565.286377
last_received_ts = 565.285889
last_seq = 10239
current_ts = 565.451904
current_received_ts = 565.451416
current_seq = 10249
count = 19
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 565.286377/10239, current 565.451904/10249: elapsed=165519.0us expected=164993.8us +- 825.0us, error 0.3%
(kms_flip:2479) DEBUG: name = vblank
last_ts = 565.435303
last_received_ts = 565.434875
last_seq = 10248
current_ts = 565.600830
current_received_ts = 565.600403
current_seq = 10258
count = 19
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 565.435303/10248, current 565.600830/10258: elapsed=165515.0us expected=164993.8us +- 825.0us, error 0.3%
(kms_flip:2479) DEBUG: name = flip
last_ts = 565.451904
last_received_ts = 565.451416
last_seq = 10249
current_ts = 565.617371
current_received_ts = 565.616943
current_seq = 10259
count = 20
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 565.451904/10249, current 565.617371/10259: elapsed=165515.0us expected=164993.8us +- 825.0us, error 0.3%
(kms_flip:2479) DEBUG: name = vblank
last_ts = 565.600830
last_received_ts = 565.600403
last_seq = 10258
current_ts = 565.766357
current_received_ts = 565.765869
current_seq = 10268
count = 20
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 565.600830/10258, current 565.766357/10268: elapsed=165521.0us expected=164993.8us +- 825.0us, error 0.3%
(kms_flip:2479) DEBUG: name = flip
last_ts = 565.617371
last_received_ts = 565.616943
last_seq = 10259
current_ts = 565.782898
current_received_ts = 565.782471
current_seq = 10269
count = 21
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 565.617371/10259, current 565.782898/10269: elapsed=165522.0us expected=164993.8us +- 825.0us, error 0.3%
(kms_flip:2479) DEBUG: name = vblank
last_ts = 565.766357
last_received_ts = 565.765869
last_seq = 10268
current_ts = 565.931885
current_received_ts = 565.931396
current_seq = 10278
count = 21
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 565.766357/10268, current 565.931885/10278: elapsed=165515.0us expected=164993.8us +- 825.0us, error 0.3%
(kms_flip:2479) DEBUG: name = flip
last_ts = 565.782898
last_received_ts = 565.782471
last_seq = 10269
current_ts = 565.948425
current_received_ts = 565.948059
current_seq = 10279
count = 22
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 565.782898/10269, current 565.948425/10279: elapsed=165515.0us expected=164993.8us +- 825.0us, error 0.3%
(kms_flip:2479) DEBUG: name = vblank
last_ts = 565.931885
last_received_ts = 565.931396
last_seq = 10278
current_ts = 566.097534
current_received_ts = 566.097046
current_seq = 10288
count = 22
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 565.931885/10278, current 566.097534/10288: elapsed=165639.0us expected=164993.8us +- 825.0us, error 0.4%
(kms_flip:2479) DEBUG: name = flip
last_ts = 565.948425
last_received_ts = 565.948059
last_seq = 10279
current_ts = 566.114075
current_received_ts = 566.113647
current_seq = 10289
count = 23
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 565.948425/10279, current 566.114075/10289: elapsed=165651.0us expected=164993.8us +- 825.0us, error 0.4%
(kms_flip:2479) DEBUG: name = vblank
last_ts = 566.097534
last_received_ts = 566.097046
last_seq = 10288
current_ts = 566.263184
current_received_ts = 566.262695
current_seq = 10298
count = 23
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 566.097534/10288, current 566.263184/10298: elapsed=165650.0us expected=164993.8us +- 825.0us, error 0.4%
(kms_flip:2479) DEBUG: name = flip
last_ts = 566.114075
last_received_ts = 566.113647
last_seq = 10289
current_ts = 566.279724
current_received_ts = 566.279297
current_seq = 10299
count = 24
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 566.114075/10289, current 566.279724/10299: elapsed=165654.0us expected=164993.8us +- 825.0us, error 0.4%
(kms_flip:2479) DEBUG: name = vblank
last_ts = 566.263184
last_received_ts = 566.262695
last_seq = 10298
current_ts = 566.428833
current_received_ts = 566.428345
current_seq = 10308
count = 24
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 566.263184/10298, current 566.428833/10308: elapsed=165651.0us expected=164993.8us +- 825.0us, error 0.4%
(kms_flip:2479) DEBUG: name = flip
last_ts = 566.279724
last_received_ts = 566.279297
last_seq = 10299
current_ts = 566.445374
current_received_ts = 566.444946
current_seq = 10309
count = 25
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 566.279724/10299, current 566.445374/10309: elapsed=165646.0us expected=164993.8us +- 825.0us, error 0.4%
(kms_flip:2479) DEBUG: name = vblank
last_ts = 566.428833
last_received_ts = 566.428345
last_seq = 10308
current_ts = 566.594482
current_received_ts = 566.593994
current_seq = 10318
count = 25
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 566.428833/10308, current 566.594482/10318: elapsed=165650.0us expected=164993.8us +- 825.0us, error 0.4%
(kms_flip:2479) DEBUG: name = flip
last_ts = 566.445374
last_received_ts = 566.444946
last_seq = 10309
current_ts = 566.611023
current_received_ts = 566.610596
current_seq = 10319
count = 26
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 566.445374/10309, current 566.611023/10319: elapsed=165650.0us expected=164993.8us +- 825.0us, error 0.4%
(kms_flip:2479) DEBUG: name = vblank
last_ts = 566.594482
last_received_ts = 566.593994
last_seq = 10318
current_ts = 566.760132
current_received_ts = 566.759644
current_seq = 10328
count = 26
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 566.594482/10318, current 566.760132/10328: elapsed=165650.0us expected=164993.8us +- 825.0us, error 0.4%
(kms_flip:2479) DEBUG: name = flip
last_ts = 566.611023
last_received_ts = 566.610596
last_seq = 10319
current_ts = 566.776672
current_received_ts = 566.776245
current_seq = 10329
count = 27
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 566.611023/10319, current 566.776672/10329: elapsed=165647.0us expected=164993.8us +- 825.0us, error 0.4%
(kms_flip:2479) DEBUG: name = vblank
last_ts = 566.760132
last_received_ts = 566.759644
last_seq = 10328
current_ts = 566.925781
current_received_ts = 566.925293
current_seq = 10338
count = 27
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 566.760132/10328, current 566.925781/10338: elapsed=165654.0us expected=164993.8us +- 825.0us, error 0.4%
(kms_flip:2479) DEBUG: name = flip
last_ts = 566.776672
last_received_ts = 566.776245
last_seq = 10329
current_ts = 566.942322
current_received_ts = 566.941895
current_seq = 10339
count = 28
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 566.776672/10329, current 566.942322/10339: elapsed=165654.0us expected=164993.8us +- 825.0us, error 0.4%
(kms_flip:2479) DEBUG: name = vblank
last_ts = 566.925781
last_received_ts = 566.925293
last_seq = 10338
current_ts = 567.091492
current_received_ts = 567.091125
current_seq = 10348
count = 28
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 566.925781/10338, current 567.091492/10348: elapsed=165749.0us expected=164993.8us +- 825.0us, error 0.5%
(kms_flip:2479) DEBUG: name = flip
last_ts = 566.942322
last_received_ts = 566.941895
last_seq = 10339
current_ts = 567.108093
current_received_ts = 567.107605
current_seq = 10349
count = 29
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 566.942322/10339, current 567.108093/10349: elapsed=165765.0us expected=164993.8us +- 825.0us, error 0.5%
(kms_flip:2479) DEBUG: name = vblank
last_ts = 567.091492
last_received_ts = 567.091125
last_seq = 10348
current_ts = 567.257263
current_received_ts = 567.256836
current_seq = 10358
count = 29
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 567.091492/10348, current 567.257263/10358: elapsed=165766.0us expected=164993.8us +- 825.0us, error 0.5%
(kms_flip:2479) DEBUG: name = flip
last_ts = 567.108093
last_received_ts = 567.107605
last_seq = 10349
current_ts = 567.273865
current_received_ts = 567.273438
current_seq = 10359
count = 30
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 567.108093/10349, current 567.273865/10359: elapsed=165765.0us expected=164993.8us +- 825.0us, error 0.5%
(kms_flip:2479) DEBUG: name = vblank
last_ts = 567.257263
last_received_ts = 567.256836
last_seq = 10358
current_ts = 567.423035
current_received_ts = 567.422546
current_seq = 10368
count = 30
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 567.257263/10358, current 567.423035/10368: elapsed=165767.0us expected=164993.8us +- 825.0us, error 0.5%
(kms_flip:2479) DEBUG: name = flip
last_ts = 567.273865
last_received_ts = 567.273438
last_seq = 10359
current_ts = 567.439636
current_received_ts = 567.439148
current_seq = 10369
count = 31
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 567.273865/10359, current 567.439636/10369: elapsed=165767.0us expected=164993.8us +- 825.0us, error 0.5%
(kms_flip:2479) DEBUG: name = vblank
last_ts = 567.423035
last_received_ts = 567.422546
last_seq = 10368
current_ts = 567.588806
current_received_ts = 567.588318
current_seq = 10378
count = 31
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 567.423035/10368, current 567.588806/10378: elapsed=165766.0us expected=164993.8us +- 825.0us, error 0.5%
(kms_flip:2479) DEBUG: name = flip
last_ts = 567.439636
last_received_ts = 567.439148
last_seq = 10369
current_ts = 567.605408
current_received_ts = 567.604980
current_seq = 10379
count = 32
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 567.439636/10369, current 567.605408/10379: elapsed=165766.0us expected=164993.8us +- 825.0us, error 0.5%
(kms_flip:2479) DEBUG: name = vblank
last_ts = 567.588806
last_received_ts = 567.588318
last_seq = 10378
current_ts = 567.754578
current_received_ts = 567.754211
current_seq = 10388
count = 32
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 567.588806/10378, current 567.754578/10388: elapsed=165767.0us expected=164993.8us +- 825.0us, error 0.5%
(kms_flip:2479) DEBUG: name = flip
last_ts = 567.605408
last_received_ts = 567.604980
last_seq = 10379
current_ts = 567.771179
current_received_ts = 567.770691
current_seq = 10389
count = 33
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 567.605408/10379, current 567.771179/10389: elapsed=165766.0us expected=164993.8us +- 825.0us, error 0.5%
(kms_flip:2479) DEBUG: name = vblank
last_ts = 567.754578
last_received_ts = 567.754211
last_seq = 10388
current_ts = 567.920349
current_received_ts = 567.919861
current_seq = 10398
count = 33
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 567.754578/10388, current 567.920349/10398: elapsed=165766.0us expected=164993.8us +- 825.0us, error 0.5%
(kms_flip:2479) DEBUG: name = flip
last_ts = 567.771179
last_received_ts = 567.770691
last_seq = 10389
current_ts = 567.936951
current_received_ts = 567.936462
current_seq = 10399
count = 34
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 567.771179/10389, current 567.936951/10399: elapsed=165767.0us expected=164993.8us +- 825.0us, error 0.5%
(kms_flip:2479) DEBUG: name = vblank
last_ts = 567.920349
last_received_ts = 567.919861
last_seq = 10398
current_ts = 568.086182
current_received_ts = 568.085754
current_seq = 10408
count = 34
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 567.920349/10398, current 568.086182/10408: elapsed=165854.0us expected=164993.8us +- 825.0us, error 0.5%
(kms_flip:2479) DEBUG: inconsistent vblank ts/seq: last 567.920349/10398, current 568.086182/10408: elapsed=165854.0us expected=164993.8us
(kms_flip:2479) DEBUG: name = flip
last_ts = 567.936951
last_received_ts = 567.936462
last_seq = 10399
current_ts = 568.102783
current_received_ts = 568.102295
current_seq = 10409
count = 35
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 567.936951/10399, current 568.102783/10409: elapsed=165855.0us expected=164993.8us +- 825.0us, error 0.5%
(kms_flip:2479) DEBUG: inconsistent flip ts/seq: last 567.936951/10399, current 568.102783/10409: elapsed=165855.0us expected=164993.8us
(kms_flip:2479) DEBUG: name = vblank
last_ts = 568.086182
last_received_ts = 568.085754
last_seq = 10408
current_ts = 568.252075
current_received_ts = 568.251587
current_seq = 10418
count = 35
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 568.086182/10408, current 568.252075/10418: elapsed=165867.0us expected=164993.8us +- 825.0us, error 0.5%
(kms_flip:2479) DEBUG: inconsistent vblank ts/seq: last 568.086182/10408, current 568.252075/10418: elapsed=165867.0us expected=164993.8us
(kms_flip:2479) DEBUG: name = flip
last_ts = 568.102783
last_received_ts = 568.102295
last_seq = 10409
current_ts = 568.268677
current_received_ts = 568.268188
current_seq = 10419
count = 36
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 568.102783/10409, current 568.268677/10419: elapsed=165872.0us expected=164993.8us +- 825.0us, error 0.5%
(kms_flip:2479) DEBUG: inconsistent flip ts/seq: last 568.102783/10409, current 568.268677/10419: elapsed=165872.0us expected=164993.8us
(kms_flip:2479) DEBUG: name = vblank
last_ts = 568.252075
last_received_ts = 568.251587
last_seq = 10418
current_ts = 568.417969
current_received_ts = 568.417480
current_seq = 10428
count = 36
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 568.252075/10418, current 568.417969/10428: elapsed=165870.0us expected=164993.8us +- 825.0us, error 0.5%
(kms_flip:2479) DEBUG: inconsistent vblank ts/seq: last 568.252075/10418, current 568.417969/10428: elapsed=165870.0us expected=164993.8us
(kms_flip:2479) DEBUG: name = flip
last_ts = 568.268677
last_received_ts = 568.268188
last_seq = 10419
current_ts = 568.434509
current_received_ts = 568.434082
current_seq = 10429
count = 37
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 568.268677/10419, current 568.434509/10429: elapsed=165873.0us expected=164993.8us +- 825.0us, error 0.5%
(kms_flip:2479) DEBUG: inconsistent flip ts/seq: last 568.268677/10419, current 568.434509/10429: elapsed=165873.0us expected=164993.8us
(kms_flip:2479) DEBUG: name = vblank
last_ts = 568.417969
last_received_ts = 568.417480
last_seq = 10428
current_ts = 568.583801
current_received_ts = 568.583313
current_seq = 10438
count = 37
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 568.417969/10428, current 568.583801/10438: elapsed=165868.0us expected=164993.8us +- 825.0us, error 0.5%
(kms_flip:2479) DEBUG: inconsistent vblank ts/seq: last 568.417969/10428, current 568.583801/10438: elapsed=165868.0us expected=164993.8us
(kms_flip:2479) DEBUG: name = flip
last_ts = 568.434509
last_received_ts = 568.434082
last_seq = 10429
current_ts = 568.600403
current_received_ts = 568.599976
current_seq = 10439
count = 38
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 568.434509/10429, current 568.600403/10439: elapsed=165869.0us expected=164993.8us +- 825.0us, error 0.5%
(kms_flip:2479) DEBUG: inconsistent flip ts/seq: last 568.434509/10429, current 568.600403/10439: elapsed=165869.0us expected=164993.8us
(kms_flip:2479) DEBUG: name = vblank
last_ts = 568.583801
last_received_ts = 568.583313
last_seq = 10438
current_ts = 568.749695
current_received_ts = 568.749207
current_seq = 10448
count = 38
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 568.583801/10438, current 568.749695/10448: elapsed=165870.0us expected=164993.8us +- 825.0us, error 0.5%
(kms_flip:2479) DEBUG: inconsistent vblank ts/seq: last 568.583801/10438, current 568.749695/10448: elapsed=165870.0us expected=164993.8us
(kms_flip:2479) DEBUG: name = flip
last_ts = 568.600403
last_received_ts = 568.599976
last_seq = 10439
current_ts = 568.766235
current_received_ts = 568.765808
current_seq = 10449
count = 39
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 568.600403/10439, current 568.766235/10449: elapsed=165868.0us expected=164993.8us +- 825.0us, error 0.5%
(kms_flip:2479) DEBUG: inconsistent flip ts/seq: last 568.600403/10439, current 568.766235/10449: elapsed=165868.0us expected=164993.8us
(kms_flip:2479) DEBUG: name = vblank
last_ts = 568.749695
last_received_ts = 568.749207
last_seq = 10448
current_ts = 568.915527
current_received_ts = 568.915100
current_seq = 10458
count = 39
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 568.749695/10448, current 568.915527/10458: elapsed=165867.0us expected=164993.8us +- 825.0us, error 0.5%
(kms_flip:2479) DEBUG: inconsistent vblank ts/seq: last 568.749695/10448, current 568.915527/10458: elapsed=165867.0us expected=164993.8us
(kms_flip:2479) DEBUG: name = flip
last_ts = 568.766235
last_received_ts = 568.765808
last_seq = 10449
current_ts = 568.932129
current_received_ts = 568.931702
current_seq = 10459
count = 40
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 568.766235/10449, current 568.932129/10459: elapsed=165870.0us expected=164993.8us +- 825.0us, error 0.5%
(kms_flip:2479) DEBUG: inconsistent flip ts/seq: last 568.766235/10449, current 568.932129/10459: elapsed=165870.0us expected=164993.8us
(kms_flip:2479) DEBUG: name = vblank
last_ts = 568.915527
last_received_ts = 568.915100
last_seq = 10458
current_ts = 569.081482
current_received_ts = 569.080933
current_seq = 10468
count = 40
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 568.915527/10458, current 569.081482/10468: elapsed=165939.0us expected=164993.8us +- 825.0us, error 0.6%
(kms_flip:2479) DEBUG: inconsistent vblank ts/seq: last 568.915527/10458, current 569.081482/10468: elapsed=165939.0us expected=164993.8us
(kms_flip:2479) DEBUG: name = flip
last_ts = 568.932129
last_received_ts = 568.931702
last_seq = 10459
current_ts = 569.098083
current_received_ts = 569.097656
current_seq = 10469
count = 41
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 568.932129/10459, current 569.098083/10469: elapsed=165949.0us expected=164993.8us +- 825.0us, error 0.6%
(kms_flip:2479) DEBUG: inconsistent flip ts/seq: last 568.932129/10459, current 569.098083/10469: elapsed=165949.0us expected=164993.8us
(kms_flip:2479) DEBUG: name = vblank
last_ts = 569.081482
last_received_ts = 569.080933
last_seq = 10468
current_ts = 569.247437
current_received_ts = 569.246948
current_seq = 10478
count = 41
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 569.081482/10468, current 569.247437/10478: elapsed=165959.0us expected=164993.8us +- 825.0us, error 0.6%
(kms_flip:2479) DEBUG: inconsistent vblank ts/seq: last 569.081482/10468, current 569.247437/10478: elapsed=165959.0us expected=164993.8us
(kms_flip:2479) DEBUG: name = flip
last_ts = 569.098083
last_received_ts = 569.097656
last_seq = 10469
current_ts = 569.264038
current_received_ts = 569.263611
current_seq = 10479
count = 42
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 569.098083/10469, current 569.264038/10479: elapsed=165959.0us expected=164993.8us +- 825.0us, error 0.6%
(kms_flip:2479) DEBUG: inconsistent flip ts/seq: last 569.098083/10469, current 569.264038/10479: elapsed=165959.0us expected=164993.8us
(kms_flip:2479) DEBUG: name = vblank
last_ts = 569.247437
last_received_ts = 569.246948
last_seq = 10478
current_ts = 569.413391
current_received_ts = 569.412964
current_seq = 10488
count = 42
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 569.247437/10478, current 569.413391/10488: elapsed=165959.0us expected=164993.8us +- 825.0us, error 0.6%
(kms_flip:2479) DEBUG: inconsistent vblank ts/seq: last 569.247437/10478, current 569.413391/10488: elapsed=165959.0us expected=164993.8us
(kms_flip:2479) DEBUG: name = flip
last_ts = 569.264038
last_received_ts = 569.263611
last_seq = 10479
current_ts = 569.429993
current_received_ts = 569.429565
current_seq = 10489
count = 43
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 569.264038/10479, current 569.429993/10489: elapsed=165956.0us expected=164993.8us +- 825.0us, error 0.6%
(kms_flip:2479) DEBUG: inconsistent flip ts/seq: last 569.264038/10479, current 569.429993/10489: elapsed=165956.0us expected=164993.8us
(kms_flip:2479) DEBUG: name = vblank
last_ts = 569.413391
last_received_ts = 569.412964
last_seq = 10488
current_ts = 569.579346
current_received_ts = 569.578857
current_seq = 10498
count = 43
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 569.413391/10488, current 569.579346/10498: elapsed=165957.0us expected=164993.8us +- 825.0us, error 0.6%
(kms_flip:2479) DEBUG: inconsistent vblank ts/seq: last 569.413391/10488, current 569.579346/10498: elapsed=165957.0us expected=164993.8us
(kms_flip:2479) DEBUG: name = flip
last_ts = 569.429993
last_received_ts = 569.429565
last_seq = 10489
current_ts = 569.595947
current_received_ts = 569.595581
current_seq = 10499
count = 44
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 569.429993/10489, current 569.595947/10499: elapsed=165958.0us expected=164993.8us +- 825.0us, error 0.6%
(kms_flip:2479) DEBUG: inconsistent flip ts/seq: last 569.429993/10489, current 569.595947/10499: elapsed=165958.0us expected=164993.8us
(kms_flip:2479) DEBUG: name = vblank
last_ts = 569.579346
last_received_ts = 569.578857
last_seq = 10498
current_ts = 569.745300
current_received_ts = 569.744873
current_seq = 10508
count = 44
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 569.579346/10498, current 569.745300/10508: elapsed=165957.0us expected=164993.8us +- 825.0us, error 0.6%
(kms_flip:2479) DEBUG: inconsistent vblank ts/seq: last 569.579346/10498, current 569.745300/10508: elapsed=165957.0us expected=164993.8us
(kms_flip:2479) DEBUG: name = flip
last_ts = 569.595947
last_received_ts = 569.595581
last_seq = 10499
current_ts = 569.761902
current_received_ts = 569.761475
current_seq = 10509
count = 45
seq_step = 1
(kms_flip:2479) DEBUG: flip ts/seq: last 569.595947/10499, current 569.761902/10509: elapsed=165957.0us expected=164993.8us +- 825.0us, error 0.6%
(kms_flip:2479) DEBUG: inconsistent flip ts/seq: last 569.595947/10499, current 569.761902/10509: elapsed=165957.0us expected=164993.8us
(kms_flip:2479) DEBUG: name = vblank
last_ts = 569.745300
last_received_ts = 569.744873
last_seq = 10508
current_ts = 569.911255
current_received_ts = 569.910828
current_seq = 10518
count = 45
seq_step = 10
(kms_flip:2479) DEBUG: vblank ts/seq: last 569.745300/10508, current 569.911255/10518: elapsed=165958.0us expected=164993.8us +- 825.0us, error 0.6%
(kms_flip:2479) DEBUG: inconsistent vblank ts/seq: last 569.745300/10508, current 569.911255/10518: elapsed=165958.0us expected=164993.8us
(kms_flip:2479) INFO: Event flip: expected 461, counted 460, passrate = 76.09%, encoder type 2
(kms_flip:2479) DEBUG: dropped frames, expected 461, counted 460, passrate = 76.09%, encoder type 2
(kms_flip:2479) INFO: Event vblank: expected 461, counted 460, passrate = 73.91%, encoder type 2
(kms_flip:2479) DEBUG: dropped frames, expected 461, counted 460, passrate = 73.91%, encoder type 2
(kms_flip:2479) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1709:
(kms_flip:2479) CRITICAL: Failed assertion: !retried
(kms_flip:2479) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:2479) igt_core-INFO: Stack trace:
(kms_flip:2479) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2479) igt_core-INFO:   #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
(kms_flip:2479) igt_core-INFO:   #2 ../tests/kms_flip.c:1889 run_test()
(kms_flip:2479) igt_core-INFO:   #3 ../tests/kms_flip.c:2158 __igt_unique____real_main2045()
(kms_flip:2479) igt_core-INFO:   #4 ../tests/kms_flip.c:2045 main()
(kms_flip:2479) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2479) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2479) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-HDMI-A3: FAIL (19.470s)
Dmesg
<6> [564.150747] Console: switching to colour dummy device 80x25
<6> [564.151404] [IGT] kms_flip: executing
<7> [564.154219] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7> [564.155386] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [564.155687] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7> [564.168756] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:342:DP-1]
<7> [564.168764] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:342:DP-1]
<7> [564.173024] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:342:DP-1] disconnected
<7> [564.173572] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:354:HDMI-A-1]
<7> [564.173577] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:354:HDMI-A-1]
<7> [564.177842] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:354:HDMI-A-1] disconnected
<7> [564.178372] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:361:HDMI-A-2]
<7> [564.178377] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:361:HDMI-A-2]
<7> [564.182615] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:361:HDMI-A-2] disconnected
<7> [564.183154] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:365:DP-2]
<7> [564.183172] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:365:DP-2]
<7> [564.183435] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling AUX_USBC1
<7> [564.212095] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [564.241095] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [564.270095] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [564.299096] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [564.328096] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [564.357096] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [564.386098] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [564.415096] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [564.444096] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [564.473096] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [564.502096] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [564.531097] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [564.560096] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [564.589096] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [564.618096] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [564.647097] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [564.676097] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [564.705097] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [564.734098] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [564.763097] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [564.792097] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [564.821097] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [564.850097] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [564.879098] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [564.908097] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [564.937098] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [564.966097] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [564.995097] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [565.024099] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [565.053097] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [565.082098] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [565.111098] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [565.111314] i915 0000:03:00.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX USBC1/DDI TC1/PHY C: Too many retries, giving up. First error: -110
<7> [565.111346] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:365:DP-2] disconnected
<7> [565.112142] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:377:DP-3]
<7> [565.112165] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:377:DP-3]
<7> [565.116413] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:377:DP-3] disconnected
<7> [565.116879] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:385:HDMI-A-4]
<7> [565.116884] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:385:HDMI-A-4]
<7> [565.121162] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:385:HDMI-A-4] disconnected
<7> [565.121890] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:342:DP-1]
<7> [565.121894] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:342:DP-1]
<7> [565.126145] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:342:DP-1] disconnected
<7> [565.126175] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:354:HDMI-A-1]
<7> [565.126192] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:354:HDMI-A-1]
<7> [565.130411] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:354:HDMI-A-1] disconnected
<7> [565.130427] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:361:HDMI-A-2]
<7> [565.130431] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:361:HDMI-A-2]
<7> [565.134677] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:361:HDMI-A-2] disconnected
<7> [565.134694] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:365:DP-2]
<7> [565.134698] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:365:DP-2]
<7> [565.165098] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [565.194098] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [565.223098] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [565.252099] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [565.281099] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [565.310099] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [565.339099] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [565.368100] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [565.397099] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [565.426099] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [565.455099] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [565.484099] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [565.513099] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [565.542099] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [565.571099] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [565.600100] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [565.629099] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [565.658100] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [565.687100] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [565.716100] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [565.745100] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [565.774100] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [565.803100] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [565.832100] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [565.861102] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [565.890100] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [565.919100] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [565.948100] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [565.977100] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [566.006101] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [566.035100] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [566.064100] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [566.064302] i915 0000:03:00.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX USBC1/DDI TC1/PHY C: Too many retries, giving up. First error: -110
<7> [566.064345] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:365:DP-2] disconnected
<7> [566.064365] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:373:HDMI-A-3]
<7> [566.064371] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:373:HDMI-A-3]
<7> [566.088717] i915 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:373:HDMI-A-3] ELD monitor FH-DP4K
<7> [566.088736] i915 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:373:HDMI-A-3] ELD size 36, SAD count 2
<7> [566.088902] i915 0000:03:00.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpc] NAK for addr: 0040 w(1)
<7> [566.089127] i915 0000:03:00.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpc] NAK on first message, retry
<7> [566.089489] i915 0000:03:00.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpc] NAK for addr: 0040 w(1)
<7> [566.089679] i915 0000:03:00.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [566.089693] i915 0000:03:00.0: [drm:intel_hdmi_set_edid [i915]] Assuming DP dual mode adaptor presence based on VBT
<7> [566.089860] i915 0000:03:00.0: [drm:intel_hdmi_set_edid [i915]] DP dual mode adaptor (type 1 DVI) detected (max TMDS clock: 165000 kHz)
<7> [566.090706] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "2560x1440": 60 241700 2560 2608 2640 2720 1440 1443 1448 1481 0x40 0x9 (CLOCK_HIGH)
<7> [566.090715] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "2560x1600": 60 267820 2560 2608 2640 2720 1600 1603 1608 1641 0x40 0x9 (CLOCK_HIGH)
<7> [566.090723] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080": 120 297000 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5 (CLOCK_HIGH)
<7> [566.090730] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 30 266640 3840 3888 3920 4000 2160 2163 2168 2222 0x40 0x9 (CLOCK_HIGH)
<7> [566.090736] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080": 120 296703 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5 (CLOCK_HIGH)
<7> [566.090745] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:373:HDMI-A-3] probed modes:
<7> [566.090749] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [566.090768] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 17 151100 3840 3888 3920 4000 2160 2163 2168 2222 0x40 0x9
<7> [566.090772] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1600": 30 133910 2560 2608 2640 2720 1600 1603 1608 1641 0x40 0x9
<7> [566.090776] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1440": 30 120850 2560 2608 2640 2720 1440 1443 1448 1481 0x40 0x9
<7> [566.090780] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1200": 60 154000 1920 1968 2000 2080 1200 1203 1209 1235 0x40 0x9
<7> [566.090796] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 60 148352 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [566.090800] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1680x1050": 60 119000 1680 1728 1760 1840 1050 1053 1059 1080 0x40 0x9
<7> [566.090804] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x900": 60 108000 1600 1624 1704 1800 900 901 904 1000 0x40 0x5
<7> [566.090808] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x1024": 75 135000 1280 1296 1440 1688 1024 1025 1028 1066 0x40 0x5
<7> [566.090812] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x1024": 60 108000 1280 1328 1440 1688 1024 1025 1028 1066 0x40 0x5
<7> [566.090815] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1440x900": 60 88750 1440 1488 1520 1600 900 903 909 926 0x40 0x9
<7> [566.090819] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x800": 60 71000 1280 1328 1360 1440 800 803 809 823 0x40 0x9
<7> [566.090823] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1152x864": 75 108000 1152 1216 1344 1600 864 865 868 900 0x40 0x5
<7> [566.090827] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74250 1280 1390 1430 1650 720 725 730 750 0x40 0x5
<7> [566.090830] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74176 1280 1390 1430 1650 720 725 730 750 0x40 0x5
<7> [566.090834] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 75 78750 1024 1040 1136 1312 768 769 772 800 0x40 0x5
<7> [566.090838] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 70 75000 1024 1048 1184 1328 768 771 777 806 0x40 0xa
<7> [566.090842] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [566.090845] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 75 49500 800 816 896 1056 600 601 604 625 0x40 0x5
<7> [566.090849] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 72 50000 800 856 976 1040 600 637 643 666 0x40 0x5
<7> [566.090853] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5
<7> [566.090856] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 56 36000 800 824 896 1024 600 601 603 625 0x40 0x5
<7> [566.090860] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 75 31500 640 656 720 840 480 481 484 500 0x40 0xa
<7> [566.090864] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 73 31500 640 664 704 832 480 489 492 520 0x40 0xa
<7> [566.090867] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25200 640 656 752 800 480 490 492 525 0x40 0xa
<7> [566.090871] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7> [566.090897] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:377:DP-3]
<7> [566.090902] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:377:DP-3]
<7> [566.095160] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:377:DP-3] disconnected
<7> [566.095192] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:385:HDMI-A-4]
<7> [566.095196] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:385:HDMI-A-4]
<7> [566.099416] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:385:HDMI-A-4] disconnected
<6> [566.106041] [IGT] kms_flip: starting subtest flip-vs-absolute-wf_vblank-interruptible
<6> [566.113592] [IGT] kms_flip: starting dynamic subtest A-HDMI-A3
<7> [566.114019] i915 0000:03:00.0: [drm:drm_mode_addfb2] [FB:391]
<7> [566.114183] i915 0000:03:00.0: [drm:drm_mode_addfb2] [FB:392]
<7> [566.114605] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [566.115130] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [566.157909] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [566.158291] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [566.165268] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [566.165621] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [566.168172] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling AUX_USBC1
<7> [566.184654] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [566.185020] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [566.193906] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:108:pipe A]
<7> [566.194016] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [566.194263] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [566.194449] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [566.194631] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [566.194812] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [566.194994] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [566.195279] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [566.195462] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [566.195643] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [566.195823] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [566.196005] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [566.196238] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [566.196421] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [566.196603] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [566.196784] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [566.196966] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [566.197183] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [566.197365] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [566.197546] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [566.197728] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [566.197910] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [566.198116] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [566.198313] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [566.198494] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [566.198675] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [566.198857] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [566.199037] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [566.199264] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [566.199508] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [566.199691] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [566.199872] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [566.200054] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [566.200348] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [566.200530] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [566.200712] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [566.200893] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [566.201074] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in port_clock (expected 148500, found 0)
<7> [566.201302] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:108:pipe A] fastset requirement not met, forcing full modeset
<7> [566.201484] i915 0000:03:00.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:108:pipe A] releasing DPLL 2
<7> [566.201725] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [566.201904] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:108:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [566.202158] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:33:plane 1A] ddb ( 0 - 2012) -> ( 0 - 0), size 2012 -> 0
<7> [566.202349] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:103:cursor A] ddb (2012 - 2048) -> ( 0 - 0), size 36 -> 0
<7> [566.202526] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:33: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> [566.202704] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] lines 1, 5, 5, 5, 8, 8, 9, 10, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [566.202883] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] blocks 16, 81, 81, 81, 129, 129, 145, 161, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [566.203060] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] min_ddb 19, 91, 91, 91, 143, 143, 161, 179, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [566.203300] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:108:pipe A] data rate 0 num active planes 0
<7> [566.203481] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 34000 required 0
<7> [566.203678] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:108:pipe A] min cdclk: 74250 kHz -> 0 kHz
<7> [566.203875] i915 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 9282 kHz -> 0 kHz
<7> [566.204058] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:108:pipe A] enable: no [modeset]
<7> [566.204281] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:33:plane 1A] fb: [NOFB], visible: no
<7> [566.204461] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:43:plane 2A] fb: [NOFB], visible: no
<7> [566.204642] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:53:plane 3A] fb: [NOFB], visible: no
<7> [566.204822] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:63:plane 4A] fb: [NOFB], visible: no
<7> [566.205002] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:73:plane 5A] fb: [NOFB], visible: no
<7> [566.205218] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:83:plane 6A] fb: [NOFB], visible: no
<7> [566.205399] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:93:plane 7A] fb: [NOFB], visible: no
<7> [566.205579] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:103:cursor A] fb: [NOFB], visible: no
<7> [566.217938] i915 0000:03:00.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [566.235280] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_TC1
<7> [566.235499] i915 0000:03:00.0: [drm:intel_dpll_disable [i915]] disable DPLL 2 (active 0x1, on? 1) for [CRTC:108:pipe A]
<7> [566.235712] i915 0000:03:00.0: [drm:intel_dpll_disable [i915]] disabling DPLL 2
<7> [566.235913] i915 0000:03:00.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:33:plane 1A]
<7> [566.236114] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:341:DDI A/PHY A]
<7> [566.236339] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:343:DP-MST A]
<7> [566.236518] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:344:DP-MST B]
<7> [566.236697] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:345:DP-MST C]
<7> [566.236876] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:346:DP-MST D]
<7> [566.237054] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:360:DDI B/PHY B]
<7> [566.237273] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:364:DDI TC1/PHY C]
<7> [566.237472] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:366:DP-MST A]
<7> [566.237652] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:367:DP-MST B]
<7> [566.237830] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:368:DP-MST C]
<7> [566.238009] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:369:DP-MST D]
<7> [566.238230] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:376:DDI TC2/PHY D]
<7> [566.238409] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:378:DP-MST A]
<7> [566.238588] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:379:DP-MST B]
<7> [566.238767] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:380:DP-MST C]
<7> [566.238946] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:381:DP-MST D]
<7> [566.239163] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:373:HDMI-A-3]
<7> [566.239459] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [566.239703] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [566.239921] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [566.240178] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:108:pipe A]
<7> [566.240804] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:185:pipe B]
<7> [566.240986] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:262:pipe C]
<7> [566.241231] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:339:pipe D]
<7> [566.241404] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:108:pipe A]
<7> [566.241441] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:373:HDMI-A-3]
<7> [566.241518] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CONNECTOR:373:HDMI-A-3] Limiting display bpp to 24 (EDID bpp 0, max requested bpp 36, max platform bpp 36)
<7> [566.241709] i915 0000:03:00.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [566.241882] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:108:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [566.242064] i915 0000:03:00.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:364:DDI TC1/PHY C] [CRTC:108:pipe A]
<7> [566.242280] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [566.242461] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [566.242643] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [566.242824] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [566.243006] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [566.243232] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [566.243414] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [566.243594] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [566.243773] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [566.243954] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [566.244177] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [566.244358] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [566.244545] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [566.244727] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [566.244909] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [566.245115] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [566.245311] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [566.245493] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [566.245674] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [566.245857] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [566.246037] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [566.246261] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [566.246443] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [566.246624] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [566.246805] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [566.246986] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [566.247206] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [566.247389] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [566.247570] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [566.247751] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [566.247933] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [566.248142] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [566.248337] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [566.248518] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [566.248700] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [566.248881] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [566.249062] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in port_clock (expected 0, found 148500)
<7> [566.249315] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:108:pipe A] fastset requirement not met, forcing full modeset
<7> [566.249526] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [566.249704] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:108:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x1
<7> [566.249921] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:33:plane 1A] ddb ( 0 - 0) -> ( 0 - 2012), size 0 -> 2012
<7> [566.250140] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:103:cursor A] ddb ( 0 - 0) -> (2012 - 2048), size 0 -> 36
<7> [566.250317] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:33: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> [566.250495] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 5, 5, 5, 8, 8, 9, 10, 0, 0, 0
<7> [566.250673] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 81, 81, 81, 129, 129, 145, 161, 30, 0, 0
<7> [566.250852] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 91, 91, 91, 143, 143, 161, 179, 31, 0, 0
<7> [566.251049] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:108:pipe A] data rate 594000 num active planes 1
<7> [566.251268] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 34000 required 594
<7> [566.251447] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [566.251643] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:108:pipe A] min cdclk: 0 kHz -> 74250 kHz
<7> [566.251840] i915 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 9282 kHz
<7> [566.252021] i915 0000:03:00.0: [drm:intel_find_dpll [i915]] [CRTC:108:pipe A] allocated DPLL 2
<7> [566.252241] i915 0000:03:00.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:108:pipe A] reserving DPLL 2
<7> [566.252426] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:108:pipe A] enable: yes [modeset]
<7> [566.252607] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [566.252787] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [566.252967] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [566.253189] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [566.253369] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [566.253549] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [566.253729] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [566.253908] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [566.254088] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [566.254309] i915 0000:03:00.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> [566.254490] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1081, vmax vblank: 1081, vmin vtotal: 1125, vmax vtotal: 1125
<7> [566.254671] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [566.254853] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [566.255034] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [566.255253] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [566.255434] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [566.255615] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port clock: 148500, pipe src: 1920x1080+0+0, pixel rate 148500, min cdclk 74250
<7> [566.255796] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] linetime: 119, ips linetime: 0
<7> [566.255976] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [566.256194] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [566.256375] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [566.256554] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1001d0, 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> [566.256734] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [566.256915] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [566.257121] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [566.257317] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [566.257498] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [566.257677] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [566.257857] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [566.258036] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [566.258253] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [566.258433] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [566.258613] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [566.258792] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [566.258972] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [566.259191] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:33:plane 1A] fb: [FB:391] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [566.259373] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [566.259554] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [566.259734] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:43:plane 2A] fb: [NOFB], visible: no
<7> [566.259914] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:53:plane 3A] fb: [NOFB], visible: no
<7> [566.260117] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:63:plane 4A] fb: [NOFB], visible: no
<7> [566.260311] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:73:plane 5A] fb: [NOFB], visible: no
<7> [566.260491] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:83:plane 6A] fb: [NOFB], visible: no
<7> [566.260671] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:93:plane 7A] fb: [NOFB], visible: no
<7> [566.260850] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:103:cursor A] fb: [NOFB], visible: no
<7> [566.261339] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [566.261532] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [566.261738] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:341:DDI A/PHY A]
<7> [566.261918] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:343:DP-MST A]
<7> [566.262124] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:344:DP-MST B]
<7> [566.262355] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:345:DP-MST C]
<7> [566.262533] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:346:DP-MST D]
<7> [566.262711] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:360:DDI B/PHY B]
<7> [566.262888] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:364:DDI TC1/PHY C]
<7> [566.263067] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:366:DP-MST A]
<7> [566.263288] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:367:DP-MST B]
<7> [566.263466] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:368:DP-MST C]
<7> [566.263644] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:369:DP-MST D]
<7> [566.263823] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:376:DDI TC2/PHY D]
<7> [566.264002] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:378:DP-MST A]
<7> [566.264220] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:379:DP-MST B]
<7> [566.264399] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:380:DP-MST C]
<7> [566.264578] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:381:DP-MST D]
<7> [566.264834] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [566.265219] i915 0000:03:00.0: [drm:intel_dpll_enable [i915]] enable DPLL 2 (active 0x1, on? 0) for [CRTC:108:pipe A]
<7> [566.265401] i915 0000:03:00.0: [drm:intel_dpll_enable [i915]] enabling DPLL 2
<7> [566.265631] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_TC1
<7> [566.265891] i915 0000:03:00.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [566.266507] i915 0000:03:00.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [566.266690] i915 0000:03:00.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:33:plane 1A]
<7> [566.283472] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:373:HDMI-A-3]
<7> [566.283773] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:108:pipe A]
<7> [577.460034] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:108:pipe A]
<7> [577.460649] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [577.461724] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [577.462773] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [577.463818] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [577.464846] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [577.465390] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [577.465567] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [577.465745] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [577.465923] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [577.466099] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [577.466349] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [577.466541] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [577.466718] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [577.466897] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [577.467073] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [577.467301] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [577.467511] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [577.467702] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [577.467880] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [577.468072] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [577.468307] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [577.468530] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [577.468722] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [577.468898] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [577.469074] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [577.469324] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [577.469561] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [577.469738] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [577.469943] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [577.470120] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [577.470365] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [577.470545] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [577.470737] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [577.470932] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [577.471138] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [577.471346] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [577.471542] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in port_clock (expected 148500, found 0)
<7> [577.471736] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:108:pipe A] fastset requirement not met, forcing full modeset
<7> [577.471930] i915 0000:03:00.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:108:pipe A] releasing DPLL 2
<7> [577.472165] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [577.472364] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:108:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [577.472600] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:33:plane 1A] ddb ( 0 - 2012) -> ( 0 - 0), size 2012 -> 0
<7> [577.472790] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:103:cursor A] ddb (2012 - 2048) -> ( 0 - 0), size 36 -> 0
<7> [577.472980] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:33: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> [577.473169] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] lines 1, 5, 5, 5, 8, 8, 9, 10, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [577.473369] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] blocks 16, 81, 81, 81, 129, 129, 145, 161, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [577.473561] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] min_ddb 19, 91, 91, 91, 143, 143, 161, 179, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [577.473773] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:108:pipe A] data rate 0 num active planes 0
<7> [577.473966] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 34000 required 0
<7> [577.474177] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:108:pipe A] min cdclk: 74250 kHz -> 0 kHz
<7> [577.474395] i915 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 9282 kHz -> 0 kHz
<7> [577.474591] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:108:pipe A] enable: no [modeset]
<7> [577.474784] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:33:plane 1A] fb: [NOFB], visible: no
<7> [577.474978] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:43:plane 2A] fb: [NOFB], visible: no
<7> [577.475172] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:53:plane 3A] fb: [NOFB], visible: no
<7> [577.475373] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:63:plane 4A] fb: [NOFB], visible: no
<7> [577.475565] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:73:plane 5A] fb: [NOFB], visible: no
<7> [577.475758] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:83:plane 6A] fb: [NOFB], visible: no
<7> [577.475951] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:93:plane 7A] fb: [NOFB], visible: no
<7> [577.476143] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:103:cursor A] fb: [NOFB], visible: no
<7> [577.483239] i915 0000:03:00.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [577.500759] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_TC1
<7> [577.500992] i915 0000:03:00.0: [drm:intel_dpll_disable [i915]] disable DPLL 2 (active 0x1, on? 1) for [CRTC:108:pipe A]
<7> [577.501219] i915 0000:03:00.0: [drm:intel_dpll_disable [i915]] disabling DPLL 2
<7> [577.501434] i915 0000:03:00.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:33:plane 1A]
<7> [577.501646] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:341:DDI A/PHY A]
<7> [577.501839] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:343:DP-MST A]
<7> [577.502032] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:344:DP-MST B]
<7> [577.502224] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:345:DP-MST C]
<7> [577.502415] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:346:DP-MST D]
<7> [577.502608] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:360:DDI B/PHY B]
<7> [577.502810] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:364:DDI TC1/PHY C]
<7> [577.503023] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:366:DP-MST A]
<7> [577.503215] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:367:DP-MST B]
<7> [577.503407] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:368:DP-MST C]
<7> [577.503598] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:369:DP-MST D]
<7> [577.503799] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:376:DDI TC2/PHY D]
<7> [577.503991] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:378:DP-MST A]
<7> [577.504182] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:379:DP-MST B]
<7> [577.504374] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:380:DP-MST C]
<7> [577.504565] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:381:DP-MST D]
<7> [577.504764] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:373:HDMI-A-3]
<7> [577.505080] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [577.505341] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [577.505574] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [577.505814] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:108:pipe A]
<7> [577.506486] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:185:pipe B]
<7> [577.506693] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:262:pipe C]
<7> [577.506875] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:339:pipe D]
<7> [577.507062] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:108:pipe A]
<7> [577.507102] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:373:HDMI-A-3]
<7> [577.507184] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CONNECTOR:373:HDMI-A-3] Limiting display bpp to 24 (EDID bpp 0, max requested bpp 36, max platform bpp 36)
<7> [577.507388] i915 0000:03:00.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [577.507574] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:108:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [577.507777] i915 0000:03:00.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:364:DDI TC1/PHY C] [CRTC:108:pipe A]
<7> [577.507966] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [577.508160] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [577.508356] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [577.508552] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [577.508759] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [577.508954] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [577.509149] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [577.509344] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [577.509538] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [577.509742] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [577.509937] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [577.510132] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [577.510327] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [577.510521] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [577.510721] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [577.510916] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [577.511110] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [577.511305] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [577.511501] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [577.511695] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [577.511951] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [577.512146] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [577.512341] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [577.512536] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [577.512738] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [577.512932] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [577.513128] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [577.513323] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [577.513516] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [577.513711] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [577.513912] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [577.514106] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [577.514300] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [577.514495] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [577.514689] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [577.514913] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [577.515108] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in port_clock (expected 0, found 148500)
<7> [577.515303] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:108:pipe A] fastset requirement not met, forcing full modeset
<7> [577.515526] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [577.515716] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:108:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x1
<7> [577.515959] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:33:plane 1A] ddb ( 0 - 0) -> ( 0 - 2012), size 0 -> 2012
<7> [577.516148] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:103:cursor A] ddb ( 0 - 0) -> (2012 - 2048), size 0 -> 36
<7> [577.516343] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:33: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> [577.516521] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 5, 5, 5, 8, 8, 9, 10, 0, 0, 0
<7> [577.516699] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 81, 81, 81, 129, 129, 145, 161, 30, 0, 0
<7> [577.516920] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 91, 91, 91, 143, 143, 161, 179, 31, 0, 0
<7> [577.517118] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:108:pipe A] data rate 594000 num active planes 1
<7> [577.517297] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 34000 required 594
<7> [577.517476] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [577.517672] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:108:pipe A] min cdclk: 0 kHz -> 74250 kHz
<7> [577.517910] i915 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 9282 kHz
<7> [577.518093] i915 0000:03:00.0: [drm:intel_find_dpll [i915]] [CRTC:108:pipe A] allocated DPLL 2
<7> [577.518275] i915 0000:03:00.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:108:pipe A] reserving DPLL 2
<7> [577.518459] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:108:pipe A] enable: yes [modeset]
<7> [577.518640] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [577.518858] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [577.519039] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [577.519219] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [577.519400] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [577.519580] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [577.519759] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [577.519983] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [577.520162] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [577.520342] i915 0000:03:00.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> [577.520522] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1081, vmax vblank: 1081, vmin vtotal: 1125, vmax vtotal: 1125
<7> [577.520701] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [577.520890] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [577.521072] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [577.521253] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [577.521434] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [577.521615] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port clock: 148500, pipe src: 1920x1080+0+0, pixel rate 148500, min cdclk 74250
<7> [577.521795] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] linetime: 119, ips linetime: 0
<7> [577.522013] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [577.522194] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [577.522374] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [577.522552] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1001d0, 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> [577.522733] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [577.522952] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [577.523132] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [577.523313] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [577.523493] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [577.523672] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [577.523856] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [577.524037] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [577.524222] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [577.524401] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [577.524580] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [577.524760] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [577.524982] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [577.525163] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:33:plane 1A] fb: [FB:391] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [577.525345] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [577.525524] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [577.525706] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:43:plane 2A] fb: [NOFB], visible: no
<7> [577.525930] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:53:plane 3A] fb: [NOFB], visible: no
<7> [577.526110] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:63:plane 4A] fb: [NOFB], visible: no
<7> [577.526290] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:73:plane 5A] fb: [NOFB], visible: no
<7> [577.526470] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:83:plane 6A] fb: [NOFB], visible: no
<7> [577.526650] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:93:plane 7A] fb: [NOFB], visible: no
<7> [577.526829] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:103:cursor A] fb: [NOFB], visible: no
<7> [577.527131] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [577.527327] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [577.527534] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:341:DDI A/PHY A]
<7> [577.527713] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:343:DP-MST A]
<7> [577.527898] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:344:DP-MST B]
<7> [577.528077] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:345:DP-MST C]
<7> [577.528256] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:346:DP-MST D]
<7> [577.528434] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:360:DDI B/PHY B]
<7> [577.528613] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:364:DDI TC1/PHY C]
<7> [577.528791] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:366:DP-MST A]
<7> [577.529017] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:367:DP-MST B]
<7> [577.529195] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:368:DP-MST C]
<7> [577.529373] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:369:DP-MST D]
<7> [577.529552] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:376:DDI TC2/PHY D]
<7> [577.529730] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:378:DP-MST A]
<7> [577.529939] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:379:DP-MST B]
<7> [577.530132] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:380:DP-MST C]
<7> [577.530311] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:381:DP-MST D]
<7> [577.530567] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [577.530933] i915 0000:03:00.0: [drm:intel_dpll_enable [i915]] enable DPLL 2 (active 0x1, on? 0) for [CRTC:108:pipe A]
<7> [577.531115] i915 0000:03:00.0: [drm:intel_dpll_enable [i915]] enabling DPLL 2
<7> [577.531345] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_TC1
<7> [577.531604] i915 0000:03:00.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [577.532230] i915 0000:03:00.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [577.532415] i915 0000:03:00.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:33:plane 1A]
<7> [577.549224] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:373:HDMI-A-3]
<7> [577.549525] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:108:pipe A]
<6> [585.586261] [IGT] kms_flip: finished subtest A-HDMI-A3, FAIL
Created at 2025-11-03 20:08:44