Results for igt@kms_flip@wf_vblank-ts-check-interruptible@a-hdmi-a1

Result: Fail

integration-manifest git-log-oneline i915_display_info10 igt_runner10 runtimes10 results10.json results10-i915-load.json guc_logs10.tar i915_display_info_post_exec10 boot10 dmesg10

DetailValue
Duration 18.79 seconds
Hostname 
shard-dg1-15
Igt-Version 
IGT-Version: 2.4-ga5c5d6558 (x86_64) (Linux: 7.1.0-rc1-CI_DRM_18367-g81f5e3410e2a+ x86_64)
Out 
Using IGT_SRANDOM=1777268212 for randomisation
Opened device: /dev/dri/card0
Using monotonic timestamps
Starting subtest: wf_vblank-ts-check-interruptible
Starting dynamic subtest: A-HDMI-A1
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
Expected frametime: 16667us; measured 16683.5us +- 4.719us accuracy 0.08%
vblank interval differs from modeline! expected 16666.7us, measured 16684us +- 4.719us, difference 16.8us (3.6 sigma)
Event vblank: expected 451, counted 440, passrate = 13.64%, encoder type 2
Expected frametime: 16667us; measured 16890.2us +- 4.475us accuracy 0.08%
vblank interval differs from modeline! expected 16666.7us, measured 16890us +- 4.475us, difference 223.5us (49.9 sigma)
Event vblank: expected 447, counted 450, passrate = 42.22%, encoder type 2
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:1071 run_test_on_crtc_set()
  #2 ../tests/kms_flip.c:1920 run_test()
  #3 ../tests/kms_flip.c:2220 __igt_unique____real_main2105()
  #4 ../tests/kms_flip.c:2105 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest A-HDMI-A1: FAIL (18.788s)
Err 
Starting subtest: wf_vblank-ts-check-interruptible
Starting dynamic subtest: A-HDMI-A1
(kms_flip:2414) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1722:
(kms_flip:2414) CRITICAL: Failed assertion: !retried
(kms_flip:2414) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest A-HDMI-A1 failed.
**** DEBUG ****
(kms_flip:2414) DEBUG: name = vblank
last_ts = 350.779877
last_received_ts = 350.779358
last_seq = 5671
current_ts = 350.946686
current_received_ts = 350.946198
current_seq = 5681
count = 4
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 350.779877/5671, current 350.946686/5681: elapsed=166812.0us expected=166835.0us +- 834.2us, error 0.0%
(kms_flip:2414) DEBUG: name = vblank
last_ts = 350.946686
last_received_ts = 350.946198
last_seq = 5681
current_ts = 351.113525
current_received_ts = 351.113068
current_seq = 5691
count = 5
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 350.946686/5681, current 351.113525/5691: elapsed=166831.0us expected=166835.0us +- 834.2us, error 0.0%
(kms_flip:2414) DEBUG: name = vblank
last_ts = 351.113525
last_received_ts = 351.113068
last_seq = 5691
current_ts = 351.286011
current_received_ts = 351.285522
current_seq = 5701
count = 6
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 351.113525/5691, current 351.286011/5701: elapsed=172492.0us expected=166835.0us +- 834.2us, error 3.4%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 351.113525/5691, current 351.286011/5701: elapsed=172492.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 351.286011
last_received_ts = 351.285522
last_seq = 5701
current_ts = 351.459900
current_received_ts = 351.459442
current_seq = 5711
count = 7
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 351.286011/5701, current 351.459900/5711: elapsed=173896.0us expected=166835.0us +- 834.2us, error 4.2%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 351.286011/5701, current 351.459900/5711: elapsed=173896.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 351.459900
last_received_ts = 351.459442
last_seq = 5711
current_ts = 351.633789
current_received_ts = 351.633331
current_seq = 5721
count = 8
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 351.459900/5711, current 351.633789/5721: elapsed=173898.0us expected=166835.0us +- 834.2us, error 4.2%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 351.459900/5711, current 351.633789/5721: elapsed=173898.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 351.633789
last_received_ts = 351.633331
last_seq = 5721
current_ts = 351.807678
current_received_ts = 351.807251
current_seq = 5731
count = 9
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 351.633789/5721, current 351.807678/5731: elapsed=173888.0us expected=166835.0us +- 834.2us, error 4.2%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 351.633789/5721, current 351.807678/5731: elapsed=173888.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 351.807678
last_received_ts = 351.807251
last_seq = 5731
current_ts = 351.981598
current_received_ts = 351.981140
current_seq = 5741
count = 10
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 351.807678/5731, current 351.981598/5741: elapsed=173902.0us expected=166835.0us +- 834.2us, error 4.2%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 351.807678/5731, current 351.981598/5741: elapsed=173902.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 351.981598
last_received_ts = 351.981140
last_seq = 5741
current_ts = 352.155426
current_received_ts = 352.154938
current_seq = 5751
count = 11
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 351.981598/5741, current 352.155426/5751: elapsed=173845.0us expected=166835.0us +- 834.2us, error 4.2%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 351.981598/5741, current 352.155426/5751: elapsed=173845.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 352.155426
last_received_ts = 352.154938
last_seq = 5751
current_ts = 352.328430
current_received_ts = 352.327942
current_seq = 5761
count = 12
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 352.155426/5751, current 352.328430/5761: elapsed=173003.0us expected=166835.0us +- 834.2us, error 3.7%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 352.155426/5751, current 352.328430/5761: elapsed=173003.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 352.328430
last_received_ts = 352.327942
last_seq = 5761
current_ts = 352.501434
current_received_ts = 352.500946
current_seq = 5771
count = 13
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 352.328430/5761, current 352.501434/5771: elapsed=173003.0us expected=166835.0us +- 834.2us, error 3.7%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 352.328430/5761, current 352.501434/5771: elapsed=173003.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 352.501434
last_received_ts = 352.500946
last_seq = 5771
current_ts = 352.674438
current_received_ts = 352.673950
current_seq = 5781
count = 14
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 352.501434/5771, current 352.674438/5781: elapsed=173004.0us expected=166835.0us +- 834.2us, error 3.7%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 352.501434/5771, current 352.674438/5781: elapsed=173004.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 352.674438
last_received_ts = 352.673950
last_seq = 5781
current_ts = 352.847443
current_received_ts = 352.846924
current_seq = 5791
count = 15
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 352.674438/5781, current 352.847443/5791: elapsed=172994.0us expected=166835.0us +- 834.2us, error 3.7%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 352.674438/5781, current 352.847443/5791: elapsed=172994.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 352.847443
last_received_ts = 352.846924
last_seq = 5791
current_ts = 353.020447
current_received_ts = 353.019958
current_seq = 5801
count = 16
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 352.847443/5791, current 353.020447/5801: elapsed=173012.0us expected=166835.0us +- 834.2us, error 3.7%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 352.847443/5791, current 353.020447/5801: elapsed=173012.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 353.020447
last_received_ts = 353.019958
last_seq = 5801
current_ts = 353.193237
current_received_ts = 353.192780
current_seq = 5811
count = 17
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 353.020447/5801, current 353.193237/5811: elapsed=172790.0us expected=166835.0us +- 834.2us, error 3.6%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 353.020447/5801, current 353.193237/5811: elapsed=172790.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 353.193237
last_received_ts = 353.192780
last_seq = 5811
current_ts = 353.365448
current_received_ts = 353.364960
current_seq = 5821
count = 18
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 353.193237/5811, current 353.365448/5821: elapsed=172223.0us expected=166835.0us +- 834.2us, error 3.2%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 353.193237/5811, current 353.365448/5821: elapsed=172223.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 353.365448
last_received_ts = 353.364960
last_seq = 5821
current_ts = 353.537689
current_received_ts = 353.537231
current_seq = 5831
count = 19
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 353.365448/5821, current 353.537689/5831: elapsed=172223.0us expected=166835.0us +- 834.2us, error 3.2%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 353.365448/5821, current 353.537689/5831: elapsed=172223.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 353.537689
last_received_ts = 353.537231
last_seq = 5831
current_ts = 353.709900
current_received_ts = 353.709412
current_seq = 5841
count = 20
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 353.537689/5831, current 353.709900/5841: elapsed=172221.0us expected=166835.0us +- 834.2us, error 3.2%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 353.537689/5831, current 353.709900/5841: elapsed=172221.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 353.709900
last_received_ts = 353.709412
last_seq = 5841
current_ts = 353.882141
current_received_ts = 353.881653
current_seq = 5851
count = 21
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 353.709900/5841, current 353.882141/5851: elapsed=172226.0us expected=166835.0us +- 834.2us, error 3.2%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 353.709900/5841, current 353.882141/5851: elapsed=172226.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 353.882141
last_received_ts = 353.881653
last_seq = 5851
current_ts = 354.054352
current_received_ts = 354.053802
current_seq = 5861
count = 22
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 353.882141/5851, current 354.054352/5861: elapsed=172214.0us expected=166835.0us +- 834.2us, error 3.2%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 353.882141/5851, current 354.054352/5861: elapsed=172214.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 354.054352
last_received_ts = 354.053802
last_seq = 5861
current_ts = 354.226257
current_received_ts = 354.225739
current_seq = 5871
count = 23
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 354.054352/5861, current 354.226257/5871: elapsed=171904.0us expected=166835.0us +- 834.2us, error 3.0%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 354.054352/5861, current 354.226257/5871: elapsed=171904.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 354.226257
last_received_ts = 354.225739
last_seq = 5871
current_ts = 354.397797
current_received_ts = 354.397308
current_seq = 5881
count = 24
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 354.226257/5871, current 354.397797/5881: elapsed=171538.0us expected=166835.0us +- 834.2us, error 2.8%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 354.226257/5871, current 354.397797/5881: elapsed=171538.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 354.397797
last_received_ts = 354.397308
last_seq = 5881
current_ts = 354.569336
current_received_ts = 354.568817
current_seq = 5891
count = 25
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 354.397797/5881, current 354.569336/5891: elapsed=171539.0us expected=166835.0us +- 834.2us, error 2.8%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 354.397797/5881, current 354.569336/5891: elapsed=171539.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 354.569336
last_received_ts = 354.568817
last_seq = 5891
current_ts = 354.740875
current_received_ts = 354.740356
current_seq = 5901
count = 26
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 354.569336/5891, current 354.740875/5901: elapsed=171537.0us expected=166835.0us +- 834.2us, error 2.8%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 354.569336/5891, current 354.740875/5901: elapsed=171537.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 354.740875
last_received_ts = 354.740356
last_seq = 5901
current_ts = 354.912415
current_received_ts = 354.911957
current_seq = 5911
count = 27
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 354.740875/5901, current 354.912415/5911: elapsed=171538.0us expected=166835.0us +- 834.2us, error 2.8%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 354.740875/5901, current 354.912415/5911: elapsed=171538.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 354.912415
last_received_ts = 354.911957
last_seq = 5911
current_ts = 355.083954
current_received_ts = 355.083466
current_seq = 5921
count = 28
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 354.912415/5911, current 355.083954/5921: elapsed=171539.0us expected=166835.0us +- 834.2us, error 2.8%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 354.912415/5911, current 355.083954/5921: elapsed=171539.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 355.083954
last_received_ts = 355.083466
last_seq = 5921
current_ts = 355.255096
current_received_ts = 355.254639
current_seq = 5931
count = 29
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 355.083954/5921, current 355.255096/5931: elapsed=171158.0us expected=166835.0us +- 834.2us, error 2.6%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 355.083954/5921, current 355.255096/5931: elapsed=171158.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 355.255096
last_received_ts = 355.254639
last_seq = 5931
current_ts = 355.426025
current_received_ts = 355.425568
current_seq = 5941
count = 30
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 355.255096/5931, current 355.426025/5941: elapsed=170940.0us expected=166835.0us +- 834.2us, error 2.5%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 355.255096/5931, current 355.426025/5941: elapsed=170940.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 355.426025
last_received_ts = 355.425568
last_seq = 5941
current_ts = 355.596985
current_received_ts = 355.596527
current_seq = 5951
count = 31
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 355.426025/5941, current 355.596985/5951: elapsed=170941.0us expected=166835.0us +- 834.2us, error 2.5%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 355.426025/5941, current 355.596985/5951: elapsed=170941.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 355.596985
last_received_ts = 355.596527
last_seq = 5951
current_ts = 355.767914
current_received_ts = 355.767487
current_seq = 5961
count = 32
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 355.596985/5951, current 355.767914/5961: elapsed=170939.0us expected=166835.0us +- 834.2us, error 2.5%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 355.596985/5951, current 355.767914/5961: elapsed=170939.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 355.767914
last_received_ts = 355.767487
last_seq = 5961
current_ts = 355.938873
current_received_ts = 355.938385
current_seq = 5971
count = 33
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 355.767914/5961, current 355.938873/5971: elapsed=170944.0us expected=166835.0us +- 834.2us, error 2.5%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 355.767914/5961, current 355.938873/5971: elapsed=170944.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 355.938873
last_received_ts = 355.938385
last_seq = 5971
current_ts = 356.109802
current_received_ts = 356.109344
current_seq = 5981
count = 34
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 355.938873/5971, current 356.109802/5981: elapsed=170927.0us expected=166835.0us +- 834.2us, error 2.5%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 355.938873/5971, current 356.109802/5981: elapsed=170927.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 356.109802
last_received_ts = 356.109344
last_seq = 5981
current_ts = 356.280334
current_received_ts = 356.279877
current_seq = 5991
count = 35
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 356.109802/5981, current 356.280334/5991: elapsed=170538.0us expected=166835.0us +- 834.2us, error 2.2%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 356.109802/5981, current 356.280334/5991: elapsed=170538.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 356.280334
last_received_ts = 356.279877
last_seq = 5991
current_ts = 356.450745
current_received_ts = 356.450287
current_seq = 6001
count = 36
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 356.280334/5991, current 356.450745/6001: elapsed=170421.0us expected=166835.0us +- 834.2us, error 2.1%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 356.280334/5991, current 356.450745/6001: elapsed=170421.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 356.450745
last_received_ts = 356.450287
last_seq = 6001
current_ts = 356.621155
current_received_ts = 356.620697
current_seq = 6011
count = 37
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 356.450745/6001, current 356.621155/6011: elapsed=170412.0us expected=166835.0us +- 834.2us, error 2.1%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 356.450745/6001, current 356.621155/6011: elapsed=170412.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 356.621155
last_received_ts = 356.620697
last_seq = 6011
current_ts = 356.791565
current_received_ts = 356.791168
current_seq = 6021
count = 38
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 356.621155/6011, current 356.791565/6021: elapsed=170416.0us expected=166835.0us +- 834.2us, error 2.1%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 356.621155/6011, current 356.791565/6021: elapsed=170416.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 356.791565
last_received_ts = 356.791168
last_seq = 6021
current_ts = 356.962006
current_received_ts = 356.961517
current_seq = 6031
count = 39
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 356.791565/6021, current 356.962006/6031: elapsed=170417.0us expected=166835.0us +- 834.2us, error 2.1%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 356.791565/6021, current 356.962006/6031: elapsed=170417.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 356.962006
last_received_ts = 356.961517
last_seq = 6031
current_ts = 357.132416
current_received_ts = 357.131927
current_seq = 6041
count = 40
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 356.962006/6031, current 357.132416/6041: elapsed=170417.0us expected=166835.0us +- 834.2us, error 2.1%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 356.962006/6031, current 357.132416/6041: elapsed=170417.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 357.132416
last_received_ts = 357.131927
last_seq = 6041
current_ts = 357.302399
current_received_ts = 357.301941
current_seq = 6051
count = 41
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 357.132416/6041, current 357.302399/6051: elapsed=169997.0us expected=166835.0us +- 834.2us, error 1.9%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 357.132416/6041, current 357.302399/6051: elapsed=169997.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 357.302399
last_received_ts = 357.301941
last_seq = 6051
current_ts = 357.472351
current_received_ts = 357.471893
current_seq = 6061
count = 42
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 357.302399/6051, current 357.472351/6061: elapsed=169958.0us expected=166835.0us +- 834.2us, error 1.9%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 357.302399/6051, current 357.472351/6061: elapsed=169958.0us expected=166835.0us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 357.472351
last_received_ts = 357.471893
last_seq = 6061
current_ts = 357.642334
current_received_ts = 357.641846
current_seq = 6071
count = 43
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 357.472351/6061, current 357.642334/6071: elapsed=169961.0us expected=166835.0us +- 834.2us, error 1.9%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 357.472351/6061, current 357.642334/6071: elapsed=169961.0us expected=166835.0us
(kms_flip:2414) INFO: Event vblank: expected 451, counted 440, passrate = 13.64%, encoder type 2
(kms_flip:2414) DEBUG: dropped frames, expected 451, counted 440, passrate = 13.64%, encoder type 2
(kms_flip:2414) DEBUG: Retrying without a hotplug event
(kms_flip:2414) DEBUG: No stale events found
(kms_flip:2414) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:2414) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:2414) INFO: Expected frametime: 16667us; measured 16890.2us +- 4.475us accuracy 0.08%
(kms_flip:2414) INFO: vblank interval differs from modeline! expected 16666.7us, measured 16890us +- 4.475us, difference 223.5us (49.9 sigma)
(kms_flip:2414) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 361.210938
current_received_ts = 361.210480
current_seq = 6283
count = 0
seq_step = 10
(kms_flip:2414) DEBUG: name = vblank
last_ts = 361.210938
last_received_ts = 361.210480
last_seq = 6283
current_ts = 361.379578
current_received_ts = 361.379150
current_seq = 6293
count = 1
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 361.210938/6283, current 361.379578/6293: elapsed=168632.0us expected=168901.9us +- 844.5us, error 0.2%
(kms_flip:2414) DEBUG: name = vblank
last_ts = 361.379578
last_received_ts = 361.379150
last_seq = 6293
current_ts = 361.548187
current_received_ts = 361.547760
current_seq = 6303
count = 2
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 361.379578/6293, current 361.548187/6303: elapsed=168631.0us expected=168901.9us +- 844.5us, error 0.2%
(kms_flip:2414) DEBUG: name = vblank
last_ts = 361.548187
last_received_ts = 361.547760
last_seq = 6303
current_ts = 361.716827
current_received_ts = 361.716370
current_seq = 6313
count = 3
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 361.548187/6303, current 361.716827/6313: elapsed=168633.0us expected=168901.9us +- 844.5us, error 0.2%
(kms_flip:2414) DEBUG: name = vblank
last_ts = 361.716827
last_received_ts = 361.716370
last_seq = 6313
current_ts = 361.885468
current_received_ts = 361.885010
current_seq = 6323
count = 4
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 361.716827/6313, current 361.885468/6323: elapsed=168631.0us expected=168901.9us +- 844.5us, error 0.2%
(kms_flip:2414) DEBUG: name = vblank
last_ts = 361.885468
last_received_ts = 361.885010
last_seq = 6323
current_ts = 362.054108
current_received_ts = 362.053619
current_seq = 6333
count = 5
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 361.885468/6323, current 362.054108/6333: elapsed=168634.0us expected=168901.9us +- 844.5us, error 0.2%
(kms_flip:2414) DEBUG: name = vblank
last_ts = 362.054108
last_received_ts = 362.053619
last_seq = 6333
current_ts = 362.222626
current_received_ts = 362.222168
current_seq = 6343
count = 6
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 362.054108/6333, current 362.222626/6343: elapsed=168524.0us expected=168901.9us +- 844.5us, error 0.2%
(kms_flip:2414) DEBUG: name = vblank
last_ts = 362.222626
last_received_ts = 362.222168
last_seq = 6343
current_ts = 362.391022
current_received_ts = 362.390564
current_seq = 6353
count = 7
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 362.222626/6343, current 362.391022/6353: elapsed=168396.0us expected=168901.9us +- 844.5us, error 0.3%
(kms_flip:2414) DEBUG: name = vblank
last_ts = 362.391022
last_received_ts = 362.390564
last_seq = 6353
current_ts = 362.559418
current_received_ts = 362.558960
current_seq = 6363
count = 8
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 362.391022/6353, current 362.559418/6363: elapsed=168398.0us expected=168901.9us +- 844.5us, error 0.3%
(kms_flip:2414) DEBUG: name = vblank
last_ts = 362.559418
last_received_ts = 362.558960
last_seq = 6363
current_ts = 362.727814
current_received_ts = 362.727356
current_seq = 6373
count = 9
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 362.559418/6363, current 362.727814/6373: elapsed=168396.0us expected=168901.9us +- 844.5us, error 0.3%
(kms_flip:2414) DEBUG: name = vblank
last_ts = 362.727814
last_received_ts = 362.727356
last_seq = 6373
current_ts = 362.896210
current_received_ts = 362.895752
current_seq = 6383
count = 10
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 362.727814/6373, current 362.896210/6383: elapsed=168397.0us expected=168901.9us +- 844.5us, error 0.3%
(kms_flip:2414) DEBUG: name = vblank
last_ts = 362.896210
last_received_ts = 362.895752
last_seq = 6383
current_ts = 363.064606
current_received_ts = 363.064148
current_seq = 6393
count = 11
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 362.896210/6383, current 363.064606/6393: elapsed=168399.0us expected=168901.9us +- 844.5us, error 0.3%
(kms_flip:2414) DEBUG: name = vblank
last_ts = 363.064606
last_received_ts = 363.064148
last_seq = 6393
current_ts = 363.232910
current_received_ts = 363.232422
current_seq = 6403
count = 12
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 363.064606/6393, current 363.232910/6403: elapsed=168289.0us expected=168901.9us +- 844.5us, error 0.4%
(kms_flip:2414) DEBUG: name = vblank
last_ts = 363.232910
last_received_ts = 363.232422
last_seq = 6403
current_ts = 363.401093
current_received_ts = 363.400635
current_seq = 6413
count = 13
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 363.232910/6403, current 363.401093/6413: elapsed=168191.0us expected=168901.9us +- 844.5us, error 0.4%
(kms_flip:2414) DEBUG: name = vblank
last_ts = 363.401093
last_received_ts = 363.400635
last_seq = 6413
current_ts = 363.569275
current_received_ts = 363.568726
current_seq = 6423
count = 14
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 363.401093/6413, current 363.569275/6423: elapsed=168184.0us expected=168901.9us +- 844.5us, error 0.4%
(kms_flip:2414) DEBUG: name = vblank
last_ts = 363.569275
last_received_ts = 363.568726
last_seq = 6423
current_ts = 363.737457
current_received_ts = 363.737030
current_seq = 6433
count = 15
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 363.569275/6423, current 363.737457/6433: elapsed=168198.0us expected=168901.9us +- 844.5us, error 0.4%
(kms_flip:2414) DEBUG: name = vblank
last_ts = 363.737457
last_received_ts = 363.737030
last_seq = 6433
current_ts = 363.905670
current_received_ts = 363.905182
current_seq = 6443
count = 16
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 363.737457/6433, current 363.905670/6443: elapsed=168185.0us expected=168901.9us +- 844.5us, error 0.4%
(kms_flip:2414) DEBUG: name = vblank
last_ts = 363.905670
last_received_ts = 363.905182
last_seq = 6443
current_ts = 364.073853
current_received_ts = 364.073395
current_seq = 6453
count = 17
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 363.905670/6443, current 364.073853/6453: elapsed=168198.0us expected=168901.9us +- 844.5us, error 0.4%
(kms_flip:2414) DEBUG: name = vblank
last_ts = 364.073853
last_received_ts = 364.073395
last_seq = 6453
current_ts = 364.241943
current_received_ts = 364.241455
current_seq = 6463
count = 18
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 364.073853/6453, current 364.241943/6463: elapsed=168088.0us expected=168901.9us +- 844.5us, error 0.5%
(kms_flip:2414) DEBUG: name = vblank
last_ts = 364.241943
last_received_ts = 364.241455
last_seq = 6463
current_ts = 364.409943
current_received_ts = 364.409515
current_seq = 6473
count = 19
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 364.241943/6463, current 364.409943/6473: elapsed=168009.0us expected=168901.9us +- 844.5us, error 0.5%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 364.241943/6463, current 364.409943/6473: elapsed=168009.0us expected=168901.9us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 364.409943
last_received_ts = 364.409515
last_seq = 6473
current_ts = 364.577972
current_received_ts = 364.577484
current_seq = 6483
count = 20
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 364.409943/6473, current 364.577972/6483: elapsed=168014.0us expected=168901.9us +- 844.5us, error 0.5%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 364.409943/6473, current 364.577972/6483: elapsed=168014.0us expected=168901.9us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 364.577972
last_received_ts = 364.577484
last_seq = 6483
current_ts = 364.745972
current_received_ts = 364.745514
current_seq = 6493
count = 21
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 364.577972/6483, current 364.745972/6493: elapsed=168012.0us expected=168901.9us +- 844.5us, error 0.5%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 364.577972/6483, current 364.745972/6493: elapsed=168012.0us expected=168901.9us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 364.745972
last_received_ts = 364.745514
last_seq = 6493
current_ts = 364.913971
current_received_ts = 364.913513
current_seq = 6503
count = 22
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 364.745972/6493, current 364.913971/6503: elapsed=168009.0us expected=168901.9us +- 844.5us, error 0.5%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 364.745972/6493, current 364.913971/6503: elapsed=168009.0us expected=168901.9us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 364.913971
last_received_ts = 364.913513
last_seq = 6503
current_ts = 365.082001
current_received_ts = 365.081543
current_seq = 6513
count = 23
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 364.913971/6503, current 365.082001/6513: elapsed=168014.0us expected=168901.9us +- 844.5us, error 0.5%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 364.913971/6503, current 365.082001/6513: elapsed=168014.0us expected=168901.9us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 365.082001
last_received_ts = 365.081543
last_seq = 6513
current_ts = 365.249908
current_received_ts = 365.249451
current_seq = 6523
count = 24
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 365.082001/6513, current 365.249908/6523: elapsed=167913.0us expected=168901.9us +- 844.5us, error 0.6%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 365.082001/6513, current 365.249908/6523: elapsed=167913.0us expected=168901.9us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 365.249908
last_received_ts = 365.249451
last_seq = 6523
current_ts = 365.417786
current_received_ts = 365.417297
current_seq = 6533
count = 25
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 365.249908/6523, current 365.417786/6533: elapsed=167858.0us expected=168901.9us +- 844.5us, error 0.6%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 365.249908/6523, current 365.417786/6533: elapsed=167858.0us expected=168901.9us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 365.417786
last_received_ts = 365.417297
last_seq = 6533
current_ts = 365.585632
current_received_ts = 365.585175
current_seq = 6543
count = 26
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 365.417786/6533, current 365.585632/6543: elapsed=167850.0us expected=168901.9us +- 844.5us, error 0.6%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 365.417786/6533, current 365.585632/6543: elapsed=167850.0us expected=168901.9us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 365.585632
last_received_ts = 365.585175
last_seq = 6543
current_ts = 365.753479
current_received_ts = 365.754211
current_seq = 6553
count = 27
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 365.585632/6543, current 365.753479/6553: elapsed=167855.0us expected=168901.9us +- 844.5us, error 0.6%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 365.585632/6543, current 365.753479/6553: elapsed=167855.0us expected=168901.9us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 365.753479
last_received_ts = 365.754211
last_seq = 6553
current_ts = 365.921326
current_received_ts = 365.920868
current_seq = 6563
count = 28
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 365.753479/6553, current 365.921326/6563: elapsed=167855.0us expected=168901.9us +- 844.5us, error 0.6%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 365.753479/6553, current 365.921326/6563: elapsed=167855.0us expected=168901.9us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 365.921326
last_received_ts = 365.920868
last_seq = 6563
current_ts = 366.089172
current_received_ts = 366.088715
current_seq = 6573
count = 29
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 365.921326/6563, current 366.089172/6573: elapsed=167844.0us expected=168901.9us +- 844.5us, error 0.6%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 365.921326/6563, current 366.089172/6573: elapsed=167844.0us expected=168901.9us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 366.089172
last_received_ts = 366.088715
last_seq = 6573
current_ts = 366.256927
current_received_ts = 366.256348
current_seq = 6583
count = 30
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 366.089172/6573, current 366.256927/6583: elapsed=167763.0us expected=168901.9us +- 844.5us, error 0.7%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 366.089172/6573, current 366.256927/6583: elapsed=167763.0us expected=168901.9us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 366.256927
last_received_ts = 366.256348
last_seq = 6583
current_ts = 366.424652
current_received_ts = 366.424164
current_seq = 6593
count = 31
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 366.256927/6583, current 366.424652/6593: elapsed=167720.0us expected=168901.9us +- 844.5us, error 0.7%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 366.256927/6583, current 366.424652/6593: elapsed=167720.0us expected=168901.9us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 366.424652
last_received_ts = 366.424164
last_seq = 6593
current_ts = 366.592377
current_received_ts = 366.591919
current_seq = 6603
count = 32
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 366.424652/6593, current 366.592377/6603: elapsed=167721.0us expected=168901.9us +- 844.5us, error 0.7%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 366.424652/6593, current 366.592377/6603: elapsed=167721.0us expected=168901.9us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 366.592377
last_received_ts = 366.591919
last_seq = 6603
current_ts = 366.760101
current_received_ts = 366.759613
current_seq = 6613
count = 33
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 366.592377/6603, current 366.760101/6613: elapsed=167717.0us expected=168901.9us +- 844.5us, error 0.7%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 366.592377/6603, current 366.760101/6613: elapsed=167717.0us expected=168901.9us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 366.760101
last_received_ts = 366.759613
last_seq = 6613
current_ts = 366.927826
current_received_ts = 366.927307
current_seq = 6623
count = 34
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 366.760101/6613, current 366.927826/6623: elapsed=167719.0us expected=168901.9us +- 844.5us, error 0.7%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 366.760101/6613, current 366.927826/6623: elapsed=167719.0us expected=168901.9us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 366.927826
last_received_ts = 366.927307
last_seq = 6623
current_ts = 367.095520
current_received_ts = 367.095093
current_seq = 6633
count = 35
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 366.927826/6623, current 367.095520/6633: elapsed=167714.0us expected=168901.9us +- 844.5us, error 0.7%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 366.927826/6623, current 367.095520/6633: elapsed=167714.0us expected=168901.9us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 367.095520
last_received_ts = 367.095093
last_seq = 6633
current_ts = 367.263153
current_received_ts = 367.262695
current_seq = 6643
count = 36
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 367.095520/6633, current 367.263153/6643: elapsed=167631.0us expected=168901.9us +- 844.5us, error 0.8%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 367.095520/6633, current 367.263153/6643: elapsed=167631.0us expected=168901.9us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 367.263153
last_received_ts = 367.262695
last_seq = 6643
current_ts = 367.430756
current_received_ts = 367.430298
current_seq = 6653
count = 37
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 367.263153/6643, current 367.430756/6653: elapsed=167594.0us expected=168901.9us +- 844.5us, error 0.8%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 367.263153/6643, current 367.430756/6653: elapsed=167594.0us expected=168901.9us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 367.430756
last_received_ts = 367.430298
last_seq = 6653
current_ts = 367.598358
current_received_ts = 367.597870
current_seq = 6663
count = 38
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 367.430756/6653, current 367.598358/6663: elapsed=167598.0us expected=168901.9us +- 844.5us, error 0.8%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 367.430756/6653, current 367.598358/6663: elapsed=167598.0us expected=168901.9us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 367.598358
last_received_ts = 367.597870
last_seq = 6663
current_ts = 367.765930
current_received_ts = 367.765472
current_seq = 6673
count = 39
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 367.598358/6663, current 367.765930/6673: elapsed=167589.0us expected=168901.9us +- 844.5us, error 0.8%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 367.598358/6663, current 367.765930/6673: elapsed=167589.0us expected=168901.9us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 367.765930
last_received_ts = 367.765472
last_seq = 6673
current_ts = 367.933533
current_received_ts = 367.933105
current_seq = 6683
count = 40
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 367.765930/6673, current 367.933533/6683: elapsed=167604.0us expected=168901.9us +- 844.5us, error 0.8%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 367.765930/6673, current 367.933533/6683: elapsed=167604.0us expected=168901.9us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 367.933533
last_received_ts = 367.933105
last_seq = 6683
current_ts = 368.101135
current_received_ts = 368.100677
current_seq = 6693
count = 41
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 367.933533/6683, current 368.101135/6693: elapsed=167595.0us expected=168901.9us +- 844.5us, error 0.8%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 367.933533/6683, current 368.101135/6693: elapsed=167595.0us expected=168901.9us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 368.101135
last_received_ts = 368.100677
last_seq = 6693
current_ts = 368.268646
current_received_ts = 368.268219
current_seq = 6703
count = 42
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 368.101135/6693, current 368.268646/6703: elapsed=167519.0us expected=168901.9us +- 844.5us, error 0.8%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 368.101135/6693, current 368.268646/6703: elapsed=167519.0us expected=168901.9us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 368.268646
last_received_ts = 368.268219
last_seq = 6703
current_ts = 368.436127
current_received_ts = 368.435699
current_seq = 6713
count = 43
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 368.268646/6703, current 368.436127/6713: elapsed=167480.0us expected=168901.9us +- 844.5us, error 0.8%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 368.268646/6703, current 368.436127/6713: elapsed=167480.0us expected=168901.9us
(kms_flip:2414) DEBUG: name = vblank
last_ts = 368.436127
last_received_ts = 368.435699
last_seq = 6713
current_ts = 368.603638
current_received_ts = 368.603149
current_seq = 6723
count = 44
seq_step = 10
(kms_flip:2414) DEBUG: vblank ts/seq: last 368.436127/6713, current 368.603638/6723: elapsed=167501.0us expected=168901.9us +- 844.5us, error 0.8%
(kms_flip:2414) DEBUG: inconsistent vblank ts/seq: last 368.436127/6713, current 368.603638/6723: elapsed=167501.0us expected=168901.9us
(kms_flip:2414) INFO: Event vblank: expected 447, counted 450, passrate = 42.22%, encoder type 2
(kms_flip:2414) DEBUG: dropped frames, expected 447, counted 450, passrate = 42.22%, encoder type 2
(kms_flip:2414) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1722:
(kms_flip:2414) CRITICAL: Failed assertion: !retried
(kms_flip:2414) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:2414) igt_core-INFO: Stack trace:
(kms_flip:2414) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2414) igt_core-INFO:   #1 ../tests/kms_flip.c:1071 run_test_on_crtc_set()
(kms_flip:2414) igt_core-INFO:   #2 ../tests/kms_flip.c:1920 run_test()
(kms_flip:2414) igt_core-INFO:   #3 ../tests/kms_flip.c:2220 __igt_unique____real_main2105()
(kms_flip:2414) igt_core-INFO:   #4 ../tests/kms_flip.c:2105 main()
(kms_flip:2414) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2414) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2414) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-HDMI-A1: FAIL (18.788s)
Dmesg
<6> [348.024657] Console: switching to colour dummy device 80x25
<6> [348.025314] [IGT] kms_flip: executing
<7> [348.029776] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7> [348.030933] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [348.031247] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7> [348.044098] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:588:DP-1]
<7> [348.044106] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:588:DP-1]
<7> [348.044368] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [348.044549] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 01 to 00
<7> [348.044902] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling AUX_A
<7> [348.065956] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.087088] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.108219] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.129358] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.150491] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.171641] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.192790] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.213939] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.235093] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.256290] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.277439] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.298588] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.319741] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.340896] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.362028] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.383183] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.404330] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.425487] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.446635] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.467787] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.488987] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.510155] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.531304] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.552453] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.573599] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.594751] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.615909] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.637060] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.658212] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.679362] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.700496] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.721644] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.721831] i915 0000:03:00.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX A/DDI A/PHY A: Too many retries, giving up. First error: -110
<7> [348.721878] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:588:DP-1] disconnected
<7> [348.722614] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:HDMI-A-2]
<7> [348.722620] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:607:HDMI-A-2]
<7> [348.726889] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:HDMI-A-2] disconnected
<7> [348.727357] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:611:DP-2]
<7> [348.727362] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:611:DP-2]
<7> [348.731573] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:611:DP-2] disconnected
<7> [348.732094] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:619:HDMI-A-3]
<7> [348.732100] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:619:HDMI-A-3]
<7> [348.736320] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:619:HDMI-A-3] disconnected
<7> [348.736813] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:623:DP-3]
<7> [348.736819] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:623:DP-3]
<7> [348.741042] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:623:DP-3] disconnected
<7> [348.741510] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:631:HDMI-A-4]
<7> [348.741515] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:631:HDMI-A-4]
<7> [348.745727] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:631:HDMI-A-4] disconnected
<7> [348.746481] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:588:DP-1]
<7> [348.746486] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:588:DP-1]
<7> [348.767611] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.788732] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.809870] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.831005] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.852141] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.873276] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.894428] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.915588] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.936747] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.957895] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [348.979047] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [349.000200] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [349.021333] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [349.042487] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [349.063635] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [349.084784] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [349.105954] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [349.127140] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [349.148349] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [349.169533] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [349.190709] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [349.211860] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [349.233024] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [349.254176] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [349.275348] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [349.296508] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [349.317667] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [349.338835] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [349.359994] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [349.381159] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [349.402318] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [349.423482] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [349.423668] i915 0000:03:00.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX A/DDI A/PHY A: Too many retries, giving up. First error: -110
<7> [349.423714] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:588:DP-1] disconnected
<7> [349.423741] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:600:HDMI-A-1]
<7> [349.423748] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:600:HDMI-A-1]
<7> [349.448714] i915 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:600:HDMI-A-1] ELD monitor FH-DP4K
<7> [349.448720] i915 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:600:HDMI-A-1] ELD size 36, SAD count 2
<7> [349.448911] i915 0000:03:00.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpa] NAK for addr: 0040 w(1)
<7> [349.449088] i915 0000:03:00.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpa] NAK on first message, retry
<7> [349.449427] i915 0000:03:00.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpa] NAK for addr: 0040 w(1)
<7> [349.449584] i915 0000:03:00.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [349.449600] i915 0000:03:00.0: [drm:intel_hdmi_set_edid [i915]] Assuming DP dual mode adaptor presence based on VBT
<7> [349.449747] 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> [349.450528] 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> [349.450537] 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> [349.450544] 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> [349.450551] 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> [349.450558] 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> [349.450566] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:600:HDMI-A-1] probed modes:
<7> [349.450570] 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> [349.450575] 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> [349.450579] 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> [349.450584] 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> [349.450588] 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> [349.450592] 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> [349.450596] 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> [349.450601] 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> [349.450605] 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> [349.450609] 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> [349.450614] 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> [349.450618] 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> [349.450622] 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> [349.450626] 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> [349.450631] 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> [349.450635] 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> [349.450639] 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> [349.450643] 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> [349.450648] 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> [349.450652] 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> [349.450656] 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> [349.450660] 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> [349.450665] 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> [349.450669] 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> [349.450673] 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> [349.450677] 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> [349.450711] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:HDMI-A-2]
<7> [349.450716] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:607:HDMI-A-2]
<7> [349.454924] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:HDMI-A-2] disconnected
<7> [349.454943] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:611:DP-2]
<7> [349.454948] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:611:DP-2]
<7> [349.459243] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:611:DP-2] disconnected
<7> [349.459261] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:619:HDMI-A-3]
<7> [349.459265] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:619:HDMI-A-3]
<7> [349.463466] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:619:HDMI-A-3] disconnected
<7> [349.463484] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:623:DP-3]
<7> [349.463488] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:623:DP-3]
<7> [349.467683] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:623:DP-3] disconnected
<7> [349.467700] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:631:HDMI-A-4]
<7> [349.467704] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:631:HDMI-A-4]
<7> [349.471911] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:631:HDMI-A-4] disconnected
<6> [349.478756] [IGT] kms_flip: starting subtest wf_vblank-ts-check-interruptible
<6> [349.484534] [IGT] kms_flip: starting dynamic subtest A-HDMI-A1
<7> [349.485085] i915 0000:03:00.0: [drm:drm_mode_addfb2] [FB:636]
<7> [349.485235] i915 0000:03:00.0: [drm:drm_mode_addfb2] [FB:638]
<7> [349.485661] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [349.486193] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [349.529067] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling AUX_A
<7> [349.529276] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [349.529472] i915 0000:03:00.0: [drm:gen9_enable_dc5 [i915]] Enabling DC5
<7> [349.529637] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 01
<7> [349.534908] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [349.535458] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [349.543539] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [349.543876] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [349.563849] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [349.564288] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [349.572581] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:171:pipe A]
<7> [349.572705] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [349.572899] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [349.573090] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [349.573268] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [349.573446] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [349.573623] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [349.573801] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [349.574065] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [349.574242] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [349.574419] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [349.574595] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [349.574822] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [349.575007] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [349.575183] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [349.575360] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [349.575539] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [349.575718] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [349.575895] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [349.576079] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [349.576256] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [349.576433] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [349.576610] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [349.576787] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [349.576993] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [349.577170] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [349.577348] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [349.577526] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [349.577703] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [349.577881] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [349.578067] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [349.578245] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [349.578425] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [349.578601] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [349.578780] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [349.578965] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [349.579146] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [349.579324] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in port_clock (expected 148500, found 0)
<7> [349.579501] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] fastset requirement not met, forcing full modeset
<7> [349.579681] i915 0000:03:00.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:171:pipe A] releasing DPLL 0
<7> [349.579889] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [349.580071] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:171:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [349.580292] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 2012) -> ( 0 - 0), size 2012 -> 0
<7> [349.580466] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:165:cursor A] ddb (2012 - 2048) -> ( 0 - 0), size 36 -> 0
<7> [349.580638] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [349.580810] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35: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> [349.580994] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35: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> [349.581170] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35: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> [349.581360] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:171:pipe A] data rate 0 num active planes 0
<7> [349.581538] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 34000 required 0
<7> [349.581712] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [349.581912] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:171:pipe A] min cdclk: 74250 kHz -> 0 kHz
<7> [349.582108] i915 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 9282 kHz -> 0 kHz
<7> [349.582290] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:171:pipe A] enable: no [modeset]
<7> [349.582466] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [NOFB], visible: no
<7> [349.582643] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [349.582820] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [349.583009] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [349.583185] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [349.583360] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:plane 6A] fb: [NOFB], visible: no
<7> [349.583536] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:155:plane 7A] fb: [NOFB], visible: no
<7> [349.583710] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:165:cursor A] fb: [NOFB], visible: no
<7> [349.583960] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [349.584142] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 01 to 00
<7> [349.600842] i915 0000:03:00.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [349.618041] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_A
<7> [349.618259] i915 0000:03:00.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:171:pipe A]
<7> [349.618473] i915 0000:03:00.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [349.618701] i915 0000:03:00.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:35:plane 1A]
<7> [349.618918] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI A/PHY A]
<7> [349.619130] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:589:DP-MST A]
<7> [349.619322] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:590:DP-MST B]
<7> [349.619513] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:591:DP-MST C]
<7> [349.619705] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:592:DP-MST D]
<7> [349.619903] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI B/PHY B]
<7> [349.620092] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DDI TC1/PHY C]
<7> [349.620284] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST A]
<7> [349.620472] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:613:DP-MST B]
<7> [349.620660] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:614:DP-MST C]
<7> [349.620852] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DP-MST D]
<7> [349.621055] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:622:DDI TC2/PHY D]
<7> [349.621244] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DP-MST A]
<7> [349.621433] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DP-MST B]
<7> [349.621622] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST C]
<7> [349.621810] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST D]
<7> [349.622009] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:600:HDMI-A-1]
<7> [349.622338] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [349.622615] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:171:pipe A]
<7> [349.623337] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:309:pipe C]
<7> [349.623568] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:447:pipe B]
<7> [349.623784] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:585:pipe D]
<7> [349.624012] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:171:pipe A]
<7> [349.624055] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:600:HDMI-A-1]
<7> [349.624150] i915 0000:03:00.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:600:HDMI-A-1] Limiting target display pipe bpp to 24 (EDID bpp 0, max requested bpp 36, max platform bpp 36)
<7> [349.624352] i915 0000:03:00.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [349.624533] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [349.624727] i915 0000:03:00.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:587:DDI A/PHY A] [CRTC:171:pipe A]
<7> [349.624922] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [349.625115] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [349.625308] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [349.625501] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [349.625693] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [349.625893] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [349.626090] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [349.626282] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [349.626475] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [349.626666] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [349.626859] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [349.627057] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [349.627248] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [349.627440] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [349.627631] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [349.627825] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [349.628094] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [349.628287] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [349.628477] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [349.628669] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [349.628868] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [349.629059] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [349.629249] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [349.629439] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [349.629631] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [349.629823] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [349.630032] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [349.630223] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [349.630413] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [349.630604] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [349.630795] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [349.630993] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [349.631185] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [349.631378] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [349.631569] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [349.631760] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [349.631958] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in port_clock (expected 0, found 148500)
<7> [349.632151] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] fastset requirement not met, forcing full modeset
<7> [349.632373] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [349.632559] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:171:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x1
<7> [349.632792] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 2012), size 0 -> 2012
<7> [349.632987] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:165:cursor A] ddb ( 0 - 0) -> (2012 - 2048), size 0 -> 36
<7> [349.633175] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [349.633364] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 5, 5, 5, 8, 8, 9, 10, 0, 0, 0
<7> [349.633552] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 81, 81, 81, 129, 129, 145, 161, 30, 0, 0
<7> [349.633738] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 91, 91, 91, 143, 143, 161, 179, 31, 0, 0
<7> [349.633953] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:171:pipe A] data rate 594000 num active planes 1
<7> [349.634145] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 34000 required 594
<7> [349.634332] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [349.634541] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:171:pipe A] min cdclk: 0 kHz -> 74250 kHz
<7> [349.634752] i915 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 9282 kHz
<7> [349.634950] i915 0000:03:00.0: [drm:intel_find_dpll [i915]] [CRTC:171:pipe A] allocated DPLL 0
<7> [349.635142] i915 0000:03:00.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:171:pipe A] reserving DPLL 0
<7> [349.635337] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:171:pipe A] enable: yes [modeset]
<7> [349.635531] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [349.635722] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [349.635920] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [349.636113] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [349.636303] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [349.636493] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [349.636683] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [349.636884] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [349.637076] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [349.637267] 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> [349.637459] 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> [349.637649] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [349.637839] 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> [349.638037] 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> [349.638229] 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> [349.638421] 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> [349.638613] 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> [349.638806] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port clock: 148500, pixel rate 148500, min cdclk 74250, min voltage level 0
<7> [349.639005] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] linetime: 119, ips linetime: 0
<7> [349.639195] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [349.639384] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [349.639573] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [349.639766] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [349.639963] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [349.640154] 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> [349.640348] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [349.640538] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [349.640728] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [349.640927] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [349.641119] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [349.641310] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [349.641499] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [349.641690] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [349.641888] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [349.642077] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [349.642268] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [349.642458] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [349.642649] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [FB:636] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [349.642841] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [349.643044] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [349.643235] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [349.643424] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [349.643616] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [349.643808] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [349.644010] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:plane 6A] fb: [NOFB], visible: no
<7> [349.644203] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:155:plane 7A] fb: [NOFB], visible: no
<7> [349.644392] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:165:cursor A] fb: [NOFB], visible: no
<7> [349.644897] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI A/PHY A]
<7> [349.645088] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:589:DP-MST A]
<7> [349.645278] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:590:DP-MST B]
<7> [349.645467] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:591:DP-MST C]
<7> [349.645658] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:592:DP-MST D]
<7> [349.645857] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI B/PHY B]
<7> [349.646045] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DDI TC1/PHY C]
<7> [349.646234] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST A]
<7> [349.646423] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:613:DP-MST B]
<7> [349.646613] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:614:DP-MST C]
<7> [349.646801] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DP-MST D]
<7> [349.646999] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:622:DDI TC2/PHY D]
<7> [349.647188] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DP-MST A]
<7> [349.647377] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DP-MST B]
<7> [349.647565] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST C]
<7> [349.647755] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST D]
<7> [349.648044] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [349.648419] i915 0000:03:00.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:171:pipe A]
<7> [349.648613] i915 0000:03:00.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [349.648867] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_A
<7> [349.649145] i915 0000:03:00.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [349.649775] i915 0000:03:00.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [349.649966] i915 0000:03:00.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:35:plane 1A]
<7> [349.666767] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:600:HDMI-A-1]
<7> [349.667063] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:171:pipe A]
<7> [349.729958] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [349.730253] i915 0000:03:00.0: [drm:gen9_enable_dc5 [i915]] Enabling DC5
<7> [349.730526] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 01
<7> [349.952994] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [349.953987] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 01 to 00
<7> [349.985746] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [349.986763] i915 0000:03:00.0: [drm:gen9_enable_dc5 [i915]] Enabling DC5
<7> [349.987744] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 01
<7> [360.255897] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:171:pipe A]
<7> [360.256436] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [360.257450] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [360.258412] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [360.259350] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [360.260314] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [360.260768] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [360.260943] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [360.261105] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [360.261267] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [360.261429] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [360.261591] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [360.261793] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [360.261985] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [360.262146] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [360.262307] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [360.262468] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [360.262628] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [360.262830] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [360.263025] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [360.263187] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [360.263348] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [360.263512] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [360.263673] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [360.263876] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [360.264142] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [360.264305] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [360.264465] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [360.264626] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [360.264851] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [360.265044] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [360.265206] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [360.265368] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [360.265530] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [360.265739] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [360.265914] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [360.266089] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [360.266263] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in port_clock (expected 148500, found 0)
<7> [360.266437] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] fastset requirement not met, forcing full modeset
<7> [360.266610] i915 0000:03:00.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:171:pipe A] releasing DPLL 0
<7> [360.266818] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [360.266987] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:171:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [360.267201] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 2012) -> ( 0 - 0), size 2012 -> 0
<7> [360.267370] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:165:cursor A] ddb (2012 - 2048) -> ( 0 - 0), size 36 -> 0
<7> [360.267537] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [360.267711] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35: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> [360.267880] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35: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> [360.268049] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35: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> [360.268235] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:171:pipe A] data rate 0 num active planes 0
<7> [360.268408] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 34000 required 0
<7> [360.268579] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [360.268779] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:171:pipe A] min cdclk: 74250 kHz -> 0 kHz
<7> [360.268974] i915 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 9282 kHz -> 0 kHz
<7> [360.269148] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:171:pipe A] enable: no [modeset]
<7> [360.269321] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [NOFB], visible: no
<7> [360.269495] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [360.269675] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [360.269847] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [360.270020] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [360.270192] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:plane 6A] fb: [NOFB], visible: no
<7> [360.270366] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:155:plane 7A] fb: [NOFB], visible: no
<7> [360.270537] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:165:cursor A] fb: [NOFB], visible: no
<7> [360.270782] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [360.270960] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 01 to 00
<7> [360.283393] i915 0000:03:00.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [360.300416] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_A
<7> [360.300632] i915 0000:03:00.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:171:pipe A]
<7> [360.300839] i915 0000:03:00.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [360.301037] i915 0000:03:00.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:35:plane 1A]
<7> [360.301232] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI A/PHY A]
<7> [360.301425] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:589:DP-MST A]
<7> [360.301596] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:590:DP-MST B]
<7> [360.301767] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:591:DP-MST C]
<7> [360.301937] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:592:DP-MST D]
<7> [360.302108] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI B/PHY B]
<7> [360.302288] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DDI TC1/PHY C]
<7> [360.302467] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST A]
<7> [360.302638] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:613:DP-MST B]
<7> [360.302809] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:614:DP-MST C]
<7> [360.302981] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DP-MST D]
<7> [360.303154] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:622:DDI TC2/PHY D]
<7> [360.303333] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DP-MST A]
<7> [360.303505] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DP-MST B]
<7> [360.303676] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST C]
<7> [360.303847] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST D]
<7> [360.304018] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:600:HDMI-A-1]
<7> [360.304320] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [360.304574] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:171:pipe A]
<7> [360.305243] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:309:pipe C]
<7> [360.305461] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:447:pipe B]
<7> [360.305655] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:585:pipe D]
<7> [360.305847] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:171:pipe A]
<7> [360.305888] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:600:HDMI-A-1]
<7> [360.305973] i915 0000:03:00.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:600:HDMI-A-1] Limiting target display pipe bpp to 24 (EDID bpp 0, max requested bpp 36, max platform bpp 36)
<7> [360.306169] i915 0000:03:00.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [360.306330] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [360.306505] i915 0000:03:00.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:587:DDI A/PHY A] [CRTC:171:pipe A]
<7> [360.306670] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [360.306844] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [360.307018] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [360.307200] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [360.307374] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [360.307547] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [360.307720] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [360.307897] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [360.308072] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [360.308253] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [360.308426] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [360.308600] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [360.308773] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [360.308948] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [360.309129] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [360.309303] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [360.309476] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [360.309650] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [360.309825] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [360.309998] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [360.310181] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [360.310357] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [360.310532] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [360.310705] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [360.310879] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [360.311052] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [360.311232] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [360.311404] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [360.311577] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [360.311750] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [360.311922] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [360.312102] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [360.312274] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [360.312448] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [360.312621] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [360.312794] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [360.312966] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in port_clock (expected 0, found 148500)
<7> [360.313230] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] fastset requirement not met, forcing full modeset
<7> [360.313433] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [360.313602] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:171:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x1
<7> [360.313818] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 2012), size 0 -> 2012
<7> [360.313986] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:165:cursor A] ddb ( 0 - 0) -> (2012 - 2048), size 0 -> 36
<7> [360.314163] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [360.314330] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 5, 5, 5, 8, 8, 9, 10, 0, 0, 0
<7> [360.314498] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 81, 81, 81, 129, 129, 145, 161, 30, 0, 0
<7> [360.314666] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 91, 91, 91, 143, 143, 161, 179, 31, 0, 0
<7> [360.314853] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:171:pipe A] data rate 594000 num active planes 1
<7> [360.315032] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 34000 required 594
<7> [360.315205] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [360.315394] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:171:pipe A] min cdclk: 0 kHz -> 74250 kHz
<7> [360.315584] i915 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 9282 kHz
<7> [360.315756] i915 0000:03:00.0: [drm:intel_find_dpll [i915]] [CRTC:171:pipe A] allocated DPLL 0
<7> [360.315931] i915 0000:03:00.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:171:pipe A] reserving DPLL 0
<7> [360.316116] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:171:pipe A] enable: yes [modeset]
<7> [360.316290] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [360.316462] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [360.316634] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [360.316807] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [360.316980] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [360.317159] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [360.317333] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [360.317507] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [360.317678] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [360.317851] 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> [360.318030] 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> [360.318202] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [360.318373] 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> [360.318545] 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> [360.318717] 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> [360.318893] 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> [360.319075] 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> [360.319250] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port clock: 148500, pixel rate 148500, min cdclk 74250, min voltage level 0
<7> [360.319424] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] linetime: 119, ips linetime: 0
<7> [360.319595] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [360.319766] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [360.319939] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [360.320118] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [360.320289] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [360.320461] 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> [360.320641] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [360.320801] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [360.321002] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [360.321208] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [360.321368] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [360.321529] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [360.321734] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [360.321897] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [360.322109] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [360.322302] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [360.322462] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [360.322624] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [360.322786] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [FB:636] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [360.323007] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [360.323204] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [360.323365] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [360.323524] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [360.323684] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [360.323845] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [360.324097] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:plane 6A] fb: [NOFB], visible: no
<7> [360.324272] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:155:plane 7A] fb: [NOFB], visible: no
<7> [360.324432] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:165:cursor A] fb: [NOFB], visible: no
<7> [360.324707] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI A/PHY A]
<7> [360.324869] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:589:DP-MST A]
<7> [360.325084] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:590:DP-MST B]
<7> [360.325274] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:591:DP-MST C]
<7> [360.325462] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:592:DP-MST D]
<7> [360.325623] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI B/PHY B]
<7> [360.325785] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DDI TC1/PHY C]
<7> [360.325993] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST A]
<7> [360.326171] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:613:DP-MST B]
<7> [360.326331] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:614:DP-MST C]
<7> [360.326553] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DP-MST D]
<7> [360.326713] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:622:DDI TC2/PHY D]
<7> [360.326897] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DP-MST A]
<7> [360.327112] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DP-MST B]
<7> [360.327272] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST C]
<7> [360.327432] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST D]
<7> [360.327668] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [360.328069] i915 0000:03:00.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:171:pipe A]
<7> [360.328248] i915 0000:03:00.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [360.328461] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_A
<7> [360.328708] i915 0000:03:00.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [360.329403] i915 0000:03:00.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [360.329569] i915 0000:03:00.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:35:plane 1A]
<7> [360.346389] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:600:HDMI-A-1]
<7> [360.346730] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:171:pipe A]
<7> [360.410932] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [360.411222] i915 0000:03:00.0: [drm:gen9_enable_dc5 [i915]] Enabling DC5
<7> [360.411479] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 01
<7> [360.633334] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [360.635220] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 01 to 00
<7> [360.663535] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [360.664544] i915 0000:03:00.0: [drm:gen9_enable_dc5 [i915]] Enabling DC5
<7> [360.665519] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 01
<6> [368.275992] [IGT] kms_flip: finished subtest A-HDMI-A1, FAIL
Created at 2026-04-27 06:32:17