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

Result: Fail

git-log-oneline i915_display_info11 igt_runner11 runtimes11 results11.json results11-i915-load.json guc_logs11.tar i915_display_info_post_exec11 boot11 dmesg11

DetailValue
Duration 18.92 seconds
Hostname 
shard-snb7
Igt-Version 
IGT-Version: 2.3-g77bf43701 (x86_64) (Linux: 7.0.0-rc5-CI_DRM_18199-g4b7edcc15b85+ x86_64)
Out 
Starting dynamic subtest: A-HDMI-A1
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
Expected frametime: 16667us; measured 16650.3us +- 6.416us accuracy 0.12%
vblank interval differs from modeline! expected 16666.7us, measured 16650us +- 6.416us, difference 16.4us (2.5 sigma)
Event vblank: expected 450, counted 450, passrate = 84.44%, encoder type 2
Expected frametime: 16667us; measured 16784.1us +- 6.098us accuracy 0.11%
vblank interval differs from modeline! expected 16666.7us, measured 16784us +- 6.098us, difference 117.5us (19.3 sigma)
Event vblank: expected 448, counted 450, passrate = 84.44%, encoder type 2
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1891 run_test()
  #3 ../tests/kms_flip.c:2162 __igt_unique____real_main2047()
  #4 ../tests/kms_flip.c:2047 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest A-HDMI-A1: FAIL (18.916s)
Err 
Starting dynamic subtest: A-HDMI-A1
(kms_flip:2481) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1711:
(kms_flip:2481) CRITICAL: Failed assertion: !retried
(kms_flip:2481) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest A-HDMI-A1 failed.
**** DEBUG ****
(kms_flip:2481) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x100000000000001, size=0)
(kms_flip:2481) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:2481) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:2481) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=1, pitch=7680)
(kms_flip:2481) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:2481) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x100000000000001, size=0)
(kms_flip:2481) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:2481) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:2481) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=7680)
(kms_flip:2481) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:2481) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2481) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2481) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS
(kms_flip:2481) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2481) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2481) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS
(kms_flip:2481) igt_kms-INFO:   1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
(kms_flip:2481) DEBUG: No stale events found
(kms_flip:2481) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:2481) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:2481) INFO: Expected frametime: 16667us; measured 16650.3us +- 6.416us accuracy 0.12%
(kms_flip:2481) INFO: vblank interval differs from modeline! expected 16666.7us, measured 16650us +- 6.416us, difference 16.4us (2.5 sigma)
(kms_flip:2481) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 157.772537
current_received_ts = 157.772110
current_seq = 1986
count = 0
seq_step = 10
(kms_flip:2481) DEBUG: name = vblank
last_ts = 157.772537
last_received_ts = 157.772110
last_seq = 1986
current_ts = 157.939026
current_received_ts = 157.938461
current_seq = 1996
count = 1
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 157.772537/1986, current 157.939026/1996: elapsed=166494.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 157.939026
last_received_ts = 157.938461
last_seq = 1996
current_ts = 158.105545
current_received_ts = 158.104965
current_seq = 2006
count = 2
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 157.939026/1996, current 158.105545/2006: elapsed=166508.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 158.105545
last_received_ts = 158.104965
last_seq = 2006
current_ts = 158.272034
current_received_ts = 158.271530
current_seq = 2016
count = 3
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 158.105545/2006, current 158.272034/2016: elapsed=166493.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 158.272034
last_received_ts = 158.271530
last_seq = 2016
current_ts = 158.438507
current_received_ts = 158.438034
current_seq = 2026
count = 4
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 158.272034/2016, current 158.438507/2026: elapsed=166479.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 158.438507
last_received_ts = 158.438034
last_seq = 2026
current_ts = 158.605011
current_received_ts = 158.604492
current_seq = 2036
count = 5
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 158.438507/2026, current 158.605011/2036: elapsed=166499.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 158.605011
last_received_ts = 158.604492
last_seq = 2036
current_ts = 158.771500
current_received_ts = 158.771027
current_seq = 2046
count = 6
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 158.605011/2036, current 158.771500/2046: elapsed=166493.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 158.771500
last_received_ts = 158.771027
last_seq = 2046
current_ts = 158.938004
current_received_ts = 158.937515
current_seq = 2056
count = 7
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 158.771500/2046, current 158.938004/2056: elapsed=166492.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 158.938004
last_received_ts = 158.937515
last_seq = 2056
current_ts = 159.104492
current_received_ts = 159.104034
current_seq = 2066
count = 8
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 158.938004/2056, current 159.104492/2066: elapsed=166492.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 159.104492
last_received_ts = 159.104034
last_seq = 2066
current_ts = 159.270966
current_received_ts = 159.270447
current_seq = 2076
count = 9
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 159.104492/2066, current 159.270966/2076: elapsed=166476.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 159.270966
last_received_ts = 159.270447
last_seq = 2076
current_ts = 159.437439
current_received_ts = 159.436951
current_seq = 2086
count = 10
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 159.270966/2076, current 159.437439/2086: elapsed=166478.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 159.437439
last_received_ts = 159.436951
last_seq = 2086
current_ts = 159.603943
current_received_ts = 159.603455
current_seq = 2096
count = 11
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 159.437439/2086, current 159.603943/2096: elapsed=166493.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 159.603943
last_received_ts = 159.603455
last_seq = 2096
current_ts = 159.770416
current_received_ts = 159.769928
current_seq = 2106
count = 12
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 159.603943/2096, current 159.770416/2106: elapsed=166481.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 159.770416
last_received_ts = 159.769928
last_seq = 2106
current_ts = 159.936905
current_received_ts = 159.936386
current_seq = 2116
count = 13
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 159.770416/2106, current 159.936905/2116: elapsed=166483.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 159.936905
last_received_ts = 159.936386
last_seq = 2116
current_ts = 160.103378
current_received_ts = 160.102890
current_seq = 2126
count = 14
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 159.936905/2116, current 160.103378/2126: elapsed=166477.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 160.103378
last_received_ts = 160.102890
last_seq = 2126
current_ts = 160.269852
current_received_ts = 160.269379
current_seq = 2136
count = 15
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 160.103378/2126, current 160.269852/2136: elapsed=166472.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 160.269852
last_received_ts = 160.269379
last_seq = 2136
current_ts = 160.436325
current_received_ts = 160.435822
current_seq = 2146
count = 16
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 160.269852/2136, current 160.436325/2146: elapsed=166473.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 160.436325
last_received_ts = 160.435822
last_seq = 2146
current_ts = 160.602798
current_received_ts = 160.602234
current_seq = 2156
count = 17
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 160.436325/2146, current 160.602798/2156: elapsed=166475.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 160.602798
last_received_ts = 160.602234
last_seq = 2156
current_ts = 160.769272
current_received_ts = 160.768753
current_seq = 2166
count = 18
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 160.602798/2156, current 160.769272/2166: elapsed=166472.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 160.769272
last_received_ts = 160.768753
last_seq = 2166
current_ts = 160.935745
current_received_ts = 160.935181
current_seq = 2176
count = 19
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 160.769272/2166, current 160.935745/2176: elapsed=166473.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 160.935745
last_received_ts = 160.935181
last_seq = 2176
current_ts = 161.102219
current_received_ts = 161.101746
current_seq = 2186
count = 20
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 160.935745/2176, current 161.102219/2186: elapsed=166473.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 161.102219
last_received_ts = 161.101746
last_seq = 2186
current_ts = 161.268677
current_received_ts = 161.268112
current_seq = 2196
count = 21
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 161.102219/2186, current 161.268677/2196: elapsed=166456.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 161.268677
last_received_ts = 161.268112
last_seq = 2196
current_ts = 161.435135
current_received_ts = 161.434647
current_seq = 2206
count = 22
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 161.268677/2196, current 161.435135/2206: elapsed=166466.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 161.435135
last_received_ts = 161.434647
last_seq = 2206
current_ts = 161.601608
current_received_ts = 161.601135
current_seq = 2216
count = 23
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 161.435135/2206, current 161.601608/2216: elapsed=166465.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 161.601608
last_received_ts = 161.601135
last_seq = 2216
current_ts = 161.768082
current_received_ts = 161.767593
current_seq = 2226
count = 24
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 161.601608/2216, current 161.768082/2226: elapsed=166476.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 161.768082
last_received_ts = 161.767593
last_seq = 2226
current_ts = 161.934540
current_received_ts = 161.934052
current_seq = 2236
count = 25
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 161.768082/2226, current 161.934540/2236: elapsed=166464.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 161.934540
last_received_ts = 161.934052
last_seq = 2236
current_ts = 162.101013
current_received_ts = 162.100494
current_seq = 2246
count = 26
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 161.934540/2236, current 162.101013/2246: elapsed=166465.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 162.101013
last_received_ts = 162.100494
last_seq = 2246
current_ts = 162.267471
current_received_ts = 162.266953
current_seq = 2256
count = 27
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 162.101013/2246, current 162.267471/2256: elapsed=166457.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 162.267471
last_received_ts = 162.266953
last_seq = 2256
current_ts = 162.433914
current_received_ts = 162.433395
current_seq = 2266
count = 28
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 162.267471/2256, current 162.433914/2266: elapsed=166455.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 162.433914
last_received_ts = 162.433395
last_seq = 2266
current_ts = 162.600388
current_received_ts = 162.599823
current_seq = 2276
count = 29
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 162.433914/2266, current 162.600388/2276: elapsed=166465.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 162.600388
last_received_ts = 162.599823
last_seq = 2276
current_ts = 162.766846
current_received_ts = 162.766373
current_seq = 2286
count = 30
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 162.600388/2276, current 162.766846/2286: elapsed=166460.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 162.766846
last_received_ts = 162.766373
last_seq = 2286
current_ts = 162.933304
current_received_ts = 162.932800
current_seq = 2296
count = 31
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 162.766846/2286, current 162.933304/2296: elapsed=166456.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 162.933304
last_received_ts = 162.932800
last_seq = 2296
current_ts = 163.099747
current_received_ts = 163.099228
current_seq = 2306
count = 32
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 162.933304/2296, current 163.099747/2306: elapsed=166448.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 163.099747
last_received_ts = 163.099228
last_seq = 2306
current_ts = 163.266220
current_received_ts = 163.265732
current_seq = 2316
count = 33
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 163.099747/2306, current 163.266220/2316: elapsed=166465.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 163.266220
last_received_ts = 163.265732
last_seq = 2316
current_ts = 163.432663
current_received_ts = 163.432098
current_seq = 2326
count = 34
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 163.266220/2316, current 163.432663/2326: elapsed=166447.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 163.432663
last_received_ts = 163.432098
last_seq = 2326
current_ts = 163.599121
current_received_ts = 163.598648
current_seq = 2336
count = 35
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 163.432663/2326, current 163.599121/2336: elapsed=166460.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 163.599121
last_received_ts = 163.598648
last_seq = 2336
current_ts = 163.765564
current_received_ts = 163.765167
current_seq = 2346
count = 36
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 163.599121/2336, current 163.765564/2346: elapsed=166440.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 163.765564
last_received_ts = 163.765167
last_seq = 2346
current_ts = 163.932022
current_received_ts = 163.931519
current_seq = 2356
count = 37
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 163.765564/2346, current 163.932022/2356: elapsed=166462.0us expected=166503.1us +- 832.5us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 163.932022
last_received_ts = 163.931519
last_seq = 2356
current_ts = 164.099503
current_received_ts = 164.099014
current_seq = 2366
count = 38
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 163.932022/2356, current 164.099503/2366: elapsed=167478.0us expected=166503.1us +- 832.5us, error 0.6%
(kms_flip:2481) DEBUG: inconsistent vblank ts/seq: last 163.932022/2356, current 164.099503/2366: elapsed=167478.0us expected=166503.1us
(kms_flip:2481) DEBUG: name = vblank
last_ts = 164.099503
last_received_ts = 164.099014
last_seq = 2366
current_ts = 164.268341
current_received_ts = 164.267868
current_seq = 2376
count = 39
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 164.099503/2366, current 164.268341/2376: elapsed=168839.0us expected=166503.1us +- 832.5us, error 1.4%
(kms_flip:2481) DEBUG: inconsistent vblank ts/seq: last 164.099503/2366, current 164.268341/2376: elapsed=168839.0us expected=166503.1us
(kms_flip:2481) DEBUG: name = vblank
last_ts = 164.268341
last_received_ts = 164.267868
last_seq = 2376
current_ts = 164.437195
current_received_ts = 164.436661
current_seq = 2386
count = 40
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 164.268341/2376, current 164.437195/2386: elapsed=168850.0us expected=166503.1us +- 832.5us, error 1.4%
(kms_flip:2481) DEBUG: inconsistent vblank ts/seq: last 164.268341/2376, current 164.437195/2386: elapsed=168850.0us expected=166503.1us
(kms_flip:2481) DEBUG: name = vblank
last_ts = 164.437195
last_received_ts = 164.436661
last_seq = 2386
current_ts = 164.606049
current_received_ts = 164.605576
current_seq = 2396
count = 41
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 164.437195/2386, current 164.606049/2396: elapsed=168856.0us expected=166503.1us +- 832.5us, error 1.4%
(kms_flip:2481) DEBUG: inconsistent vblank ts/seq: last 164.437195/2386, current 164.606049/2396: elapsed=168856.0us expected=166503.1us
(kms_flip:2481) DEBUG: name = vblank
last_ts = 164.606049
last_received_ts = 164.605576
last_seq = 2396
current_ts = 164.774887
current_received_ts = 164.774368
current_seq = 2406
count = 42
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 164.606049/2396, current 164.774887/2406: elapsed=168838.0us expected=166503.1us +- 832.5us, error 1.4%
(kms_flip:2481) DEBUG: inconsistent vblank ts/seq: last 164.606049/2396, current 164.774887/2406: elapsed=168838.0us expected=166503.1us
(kms_flip:2481) DEBUG: name = vblank
last_ts = 164.774887
last_received_ts = 164.774368
last_seq = 2406
current_ts = 164.943741
current_received_ts = 164.943115
current_seq = 2416
count = 43
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 164.774887/2406, current 164.943741/2416: elapsed=168851.0us expected=166503.1us +- 832.5us, error 1.4%
(kms_flip:2481) DEBUG: inconsistent vblank ts/seq: last 164.774887/2406, current 164.943741/2416: elapsed=168851.0us expected=166503.1us
(kms_flip:2481) DEBUG: name = vblank
last_ts = 164.943741
last_received_ts = 164.943115
last_seq = 2416
current_ts = 165.112427
current_received_ts = 165.111908
current_seq = 2426
count = 44
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 164.943741/2416, current 165.112427/2426: elapsed=168699.0us expected=166503.1us +- 832.5us, error 1.3%
(kms_flip:2481) DEBUG: inconsistent vblank ts/seq: last 164.943741/2416, current 165.112427/2426: elapsed=168699.0us expected=166503.1us
(kms_flip:2481) INFO: Event vblank: expected 450, counted 450, passrate = 84.44%, encoder type 2
(kms_flip:2481) DEBUG: dropped frames, expected 450, counted 450, passrate = 84.44%, encoder type 2
(kms_flip:2481) DEBUG: Retrying without a hotplug event
(kms_flip:2481) DEBUG: No stale events found
(kms_flip:2481) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:2481) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:2481) INFO: Expected frametime: 16667us; measured 16784.1us +- 6.098us accuracy 0.11%
(kms_flip:2481) INFO: vblank interval differs from modeline! expected 16666.7us, measured 16784us +- 6.098us, difference 117.5us (19.3 sigma)
(kms_flip:2481) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 168.760834
current_received_ts = 168.760223
current_seq = 2640
count = 0
seq_step = 10
(kms_flip:2481) DEBUG: name = vblank
last_ts = 168.760834
last_received_ts = 168.760223
last_seq = 2640
current_ts = 168.928665
current_received_ts = 168.928101
current_seq = 2650
count = 1
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 168.760834/2640, current 168.928665/2650: elapsed=167842.0us expected=167841.2us +- 839.2us, error 0.0%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 168.928665
last_received_ts = 168.928101
last_seq = 2650
current_ts = 169.096436
current_received_ts = 169.095901
current_seq = 2660
count = 2
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 168.928665/2650, current 169.096436/2660: elapsed=167756.0us expected=167841.2us +- 839.2us, error 0.1%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 169.096436
last_received_ts = 169.095901
last_seq = 2660
current_ts = 169.264084
current_received_ts = 169.263657
current_seq = 2670
count = 3
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 169.096436/2660, current 169.264084/2670: elapsed=167663.0us expected=167841.2us +- 839.2us, error 0.1%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 169.264084
last_received_ts = 169.263657
last_seq = 2670
current_ts = 169.431763
current_received_ts = 169.431198
current_seq = 2680
count = 4
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 169.264084/2670, current 169.431763/2680: elapsed=167672.0us expected=167841.2us +- 839.2us, error 0.1%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 169.431763
last_received_ts = 169.431198
last_seq = 2680
current_ts = 169.599426
current_received_ts = 169.598907
current_seq = 2690
count = 5
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 169.431763/2680, current 169.599426/2690: elapsed=167659.0us expected=167841.2us +- 839.2us, error 0.1%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 169.599426
last_received_ts = 169.598907
last_seq = 2690
current_ts = 169.767090
current_received_ts = 169.766571
current_seq = 2700
count = 6
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 169.599426/2690, current 169.767090/2700: elapsed=167666.0us expected=167841.2us +- 839.2us, error 0.1%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 169.767090
last_received_ts = 169.766571
last_seq = 2700
current_ts = 169.934738
current_received_ts = 169.934265
current_seq = 2710
count = 7
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 169.767090/2700, current 169.934738/2710: elapsed=167651.0us expected=167841.2us +- 839.2us, error 0.1%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 169.934738
last_received_ts = 169.934265
last_seq = 2710
current_ts = 170.102341
current_received_ts = 170.101776
current_seq = 2720
count = 8
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 169.934738/2710, current 170.102341/2720: elapsed=167602.0us expected=167841.2us +- 839.2us, error 0.1%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 170.102341
last_received_ts = 170.101776
last_seq = 2720
current_ts = 170.269852
current_received_ts = 170.269302
current_seq = 2730
count = 9
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 170.102341/2720, current 170.269852/2730: elapsed=167504.0us expected=167841.2us +- 839.2us, error 0.2%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 170.269852
last_received_ts = 170.269302
last_seq = 2730
current_ts = 170.437347
current_received_ts = 170.436874
current_seq = 2740
count = 10
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 170.269852/2730, current 170.437347/2740: elapsed=167508.0us expected=167841.2us +- 839.2us, error 0.2%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 170.437347
last_received_ts = 170.436874
last_seq = 2740
current_ts = 170.604858
current_received_ts = 170.604370
current_seq = 2750
count = 11
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 170.437347/2740, current 170.604858/2750: elapsed=167504.0us expected=167841.2us +- 839.2us, error 0.2%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 170.604858
last_received_ts = 170.604370
last_seq = 2750
current_ts = 170.772369
current_received_ts = 170.771851
current_seq = 2760
count = 12
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 170.604858/2750, current 170.772369/2760: elapsed=167507.0us expected=167841.2us +- 839.2us, error 0.2%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 170.772369
last_received_ts = 170.771851
last_seq = 2760
current_ts = 170.939865
current_received_ts = 170.939392
current_seq = 2770
count = 13
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 170.772369/2760, current 170.939865/2770: elapsed=167506.0us expected=167841.2us +- 839.2us, error 0.2%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 170.939865
last_received_ts = 170.939392
last_seq = 2770
current_ts = 171.107315
current_received_ts = 171.106750
current_seq = 2780
count = 14
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 170.939865/2770, current 171.107315/2780: elapsed=167442.0us expected=167841.2us +- 839.2us, error 0.2%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 171.107315
last_received_ts = 171.106750
last_seq = 2780
current_ts = 171.274673
current_received_ts = 171.274139
current_seq = 2790
count = 15
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 171.107315/2780, current 171.274673/2790: elapsed=167358.0us expected=167841.2us +- 839.2us, error 0.3%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 171.274673
last_received_ts = 171.274139
last_seq = 2790
current_ts = 171.442047
current_received_ts = 171.441528
current_seq = 2800
count = 16
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 171.274673/2790, current 171.442047/2800: elapsed=167375.0us expected=167841.2us +- 839.2us, error 0.3%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 171.442047
last_received_ts = 171.441528
last_seq = 2800
current_ts = 171.609421
current_received_ts = 171.608932
current_seq = 2810
count = 17
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 171.442047/2800, current 171.609421/2810: elapsed=167369.0us expected=167841.2us +- 839.2us, error 0.3%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 171.609421
last_received_ts = 171.608932
last_seq = 2810
current_ts = 171.776779
current_received_ts = 171.776260
current_seq = 2820
count = 18
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 171.609421/2810, current 171.776779/2820: elapsed=167364.0us expected=167841.2us +- 839.2us, error 0.3%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 171.776779
last_received_ts = 171.776260
last_seq = 2820
current_ts = 171.944153
current_received_ts = 171.943680
current_seq = 2830
count = 19
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 171.776779/2820, current 171.944153/2830: elapsed=167376.0us expected=167841.2us +- 839.2us, error 0.3%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 171.944153
last_received_ts = 171.943680
last_seq = 2830
current_ts = 172.111450
current_received_ts = 172.110977
current_seq = 2840
count = 20
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 171.944153/2830, current 172.111450/2840: elapsed=167301.0us expected=167841.2us +- 839.2us, error 0.3%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 172.111450
last_received_ts = 172.110977
last_seq = 2840
current_ts = 172.278702
current_received_ts = 172.278229
current_seq = 2850
count = 21
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 172.111450/2840, current 172.278702/2850: elapsed=167250.0us expected=167841.2us +- 839.2us, error 0.4%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 172.278702
last_received_ts = 172.278229
last_seq = 2850
current_ts = 172.445953
current_received_ts = 172.445435
current_seq = 2860
count = 22
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 172.278702/2850, current 172.445953/2860: elapsed=167256.0us expected=167841.2us +- 839.2us, error 0.3%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 172.445953
last_received_ts = 172.445435
last_seq = 2860
current_ts = 172.613205
current_received_ts = 172.612686
current_seq = 2870
count = 23
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 172.445953/2860, current 172.613205/2870: elapsed=167251.0us expected=167841.2us +- 839.2us, error 0.4%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 172.613205
last_received_ts = 172.612686
last_seq = 2870
current_ts = 172.780457
current_received_ts = 172.779938
current_seq = 2880
count = 24
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 172.613205/2870, current 172.780457/2880: elapsed=167244.0us expected=167841.2us +- 839.2us, error 0.4%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 172.780457
last_received_ts = 172.779938
last_seq = 2880
current_ts = 172.947708
current_received_ts = 172.947174
current_seq = 2890
count = 25
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 172.780457/2880, current 172.947708/2890: elapsed=167255.0us expected=167841.2us +- 839.2us, error 0.3%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 172.947708
last_received_ts = 172.947174
last_seq = 2890
current_ts = 173.114914
current_received_ts = 173.114456
current_seq = 2900
count = 26
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 172.947708/2890, current 173.114914/2900: elapsed=167198.0us expected=167841.2us +- 839.2us, error 0.4%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 173.114914
last_received_ts = 173.114456
last_seq = 2900
current_ts = 173.282043
current_received_ts = 173.281540
current_seq = 2910
count = 27
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 173.114914/2900, current 173.282043/2910: elapsed=167141.0us expected=167841.2us +- 839.2us, error 0.4%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 173.282043
last_received_ts = 173.281540
last_seq = 2910
current_ts = 173.449203
current_received_ts = 173.448746
current_seq = 2920
count = 28
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 173.282043/2910, current 173.449203/2920: elapsed=167149.0us expected=167841.2us +- 839.2us, error 0.4%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 173.449203
last_received_ts = 173.448746
last_seq = 2920
current_ts = 173.616348
current_received_ts = 173.615829
current_seq = 2930
count = 29
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 173.449203/2920, current 173.616348/2930: elapsed=167144.0us expected=167841.2us +- 839.2us, error 0.4%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 173.616348
last_received_ts = 173.615829
last_seq = 2930
current_ts = 173.783478
current_received_ts = 173.782898
current_seq = 2940
count = 30
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 173.616348/2930, current 173.783478/2940: elapsed=167141.0us expected=167841.2us +- 839.2us, error 0.4%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 173.783478
last_received_ts = 173.782898
last_seq = 2940
current_ts = 173.950623
current_received_ts = 173.950119
current_seq = 2950
count = 31
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 173.783478/2940, current 173.950623/2950: elapsed=167139.0us expected=167841.2us +- 839.2us, error 0.4%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 173.950623
last_received_ts = 173.950119
last_seq = 2950
current_ts = 174.117722
current_received_ts = 174.117142
current_seq = 2960
count = 32
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 173.950623/2950, current 174.117722/2960: elapsed=167101.0us expected=167841.2us +- 839.2us, error 0.4%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 174.117722
last_received_ts = 174.117142
last_seq = 2960
current_ts = 174.284775
current_received_ts = 174.284256
current_seq = 2970
count = 33
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 174.117722/2960, current 174.284775/2970: elapsed=167056.0us expected=167841.2us +- 839.2us, error 0.5%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 174.284775
last_received_ts = 174.284256
last_seq = 2970
current_ts = 174.451828
current_received_ts = 174.451309
current_seq = 2980
count = 34
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 174.284775/2970, current 174.451828/2980: elapsed=167053.0us expected=167841.2us +- 839.2us, error 0.5%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 174.451828
last_received_ts = 174.451309
last_seq = 2980
current_ts = 174.618896
current_received_ts = 174.618393
current_seq = 2990
count = 35
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 174.451828/2980, current 174.618896/2990: elapsed=167057.0us expected=167841.2us +- 839.2us, error 0.5%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 174.618896
last_received_ts = 174.618393
last_seq = 2990
current_ts = 174.785934
current_received_ts = 174.785400
current_seq = 3000
count = 36
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 174.618896/2990, current 174.785934/3000: elapsed=167047.0us expected=167841.2us +- 839.2us, error 0.5%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 174.785934
last_received_ts = 174.785400
last_seq = 3000
current_ts = 174.952988
current_received_ts = 174.952469
current_seq = 3010
count = 37
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 174.785934/3000, current 174.952988/3010: elapsed=167057.0us expected=167841.2us +- 839.2us, error 0.5%
(kms_flip:2481) DEBUG: name = vblank
last_ts = 174.952988
last_received_ts = 174.952469
last_seq = 3010
current_ts = 175.119995
current_received_ts = 175.119476
current_seq = 3020
count = 38
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 174.952988/3010, current 175.119995/3020: elapsed=167001.0us expected=167841.2us +- 839.2us, error 0.5%
(kms_flip:2481) DEBUG: inconsistent vblank ts/seq: last 174.952988/3010, current 175.119995/3020: elapsed=167001.0us expected=167841.2us
(kms_flip:2481) DEBUG: name = vblank
last_ts = 175.119995
last_received_ts = 175.119476
last_seq = 3020
current_ts = 175.286972
current_received_ts = 175.286438
current_seq = 3030
count = 39
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 175.119995/3020, current 175.286972/3030: elapsed=166982.0us expected=167841.2us +- 839.2us, error 0.5%
(kms_flip:2481) DEBUG: inconsistent vblank ts/seq: last 175.119995/3020, current 175.286972/3030: elapsed=166982.0us expected=167841.2us
(kms_flip:2481) DEBUG: name = vblank
last_ts = 175.286972
last_received_ts = 175.286438
last_seq = 3030
current_ts = 175.453934
current_received_ts = 175.453384
current_seq = 3040
count = 40
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 175.286972/3030, current 175.453934/3040: elapsed=166962.0us expected=167841.2us +- 839.2us, error 0.5%
(kms_flip:2481) DEBUG: inconsistent vblank ts/seq: last 175.286972/3030, current 175.453934/3040: elapsed=166962.0us expected=167841.2us
(kms_flip:2481) DEBUG: name = vblank
last_ts = 175.453934
last_received_ts = 175.453384
last_seq = 3040
current_ts = 175.620926
current_received_ts = 175.620392
current_seq = 3050
count = 41
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 175.453934/3040, current 175.620926/3050: elapsed=166981.0us expected=167841.2us +- 839.2us, error 0.5%
(kms_flip:2481) DEBUG: inconsistent vblank ts/seq: last 175.453934/3040, current 175.620926/3050: elapsed=166981.0us expected=167841.2us
(kms_flip:2481) DEBUG: name = vblank
last_ts = 175.620926
last_received_ts = 175.620392
last_seq = 3050
current_ts = 175.787888
current_received_ts = 175.787369
current_seq = 3060
count = 42
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 175.620926/3050, current 175.787888/3060: elapsed=166971.0us expected=167841.2us +- 839.2us, error 0.5%
(kms_flip:2481) DEBUG: inconsistent vblank ts/seq: last 175.620926/3050, current 175.787888/3060: elapsed=166971.0us expected=167841.2us
(kms_flip:2481) DEBUG: name = vblank
last_ts = 175.787888
last_received_ts = 175.787369
last_seq = 3060
current_ts = 175.954865
current_received_ts = 175.954376
current_seq = 3070
count = 43
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 175.787888/3060, current 175.954865/3070: elapsed=166981.0us expected=167841.2us +- 839.2us, error 0.5%
(kms_flip:2481) DEBUG: inconsistent vblank ts/seq: last 175.787888/3060, current 175.954865/3070: elapsed=166981.0us expected=167841.2us
(kms_flip:2481) DEBUG: name = vblank
last_ts = 175.954865
last_received_ts = 175.954376
last_seq = 3070
current_ts = 176.121796
current_received_ts = 176.121246
current_seq = 3080
count = 44
seq_step = 10
(kms_flip:2481) DEBUG: vblank ts/seq: last 175.954865/3070, current 176.121796/3080: elapsed=166924.0us expected=167841.2us +- 839.2us, error 0.5%
(kms_flip:2481) DEBUG: inconsistent vblank ts/seq: last 175.954865/3070, current 176.121796/3080: elapsed=166924.0us expected=167841.2us
(kms_flip:2481) INFO: Event vblank: expected 448, counted 450, passrate = 84.44%, encoder type 2
(kms_flip:2481) DEBUG: dropped frames, expected 448, counted 450, passrate = 84.44%, encoder type 2
(kms_flip:2481) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1711:
(kms_flip:2481) CRITICAL: Failed assertion: !retried
(kms_flip:2481) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:2481) igt_core-INFO: Stack trace:
(kms_flip:2481) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2481) igt_core-INFO:   #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
(kms_flip:2481) igt_core-INFO:   #2 ../tests/kms_flip.c:1891 run_test()
(kms_flip:2481) igt_core-INFO:   #3 ../tests/kms_flip.c:2162 __igt_unique____real_main2047()
(kms_flip:2481) igt_core-INFO:   #4 ../tests/kms_flip.c:2047 main()
(kms_flip:2481) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2481) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2481) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-HDMI-A1: FAIL (18.916s)
Dmesg
<6> [156.843750] [IGT] kms_flip: starting dynamic subtest A-HDMI-A1
<7> [156.844836] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:90]
<7> [156.845113] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:91]
<7> [156.878425] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:53:pipe A]
<7> [156.878624] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:72:pipe B]
<7> [156.878759] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [156.879099] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [156.879373] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [156.879654] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in has_pch_encoder (expected yes, found no)
<7> [156.879937] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in fdi_lanes (expected 1, found 0)
<7> [156.880222] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in fdi_m_n (expected tu 64 data 6039797/8388608 link 125829/524288, found tu 0, data 0/0 link 0/0)
<7> [156.880509] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in output_types (expected 0x00000002, found 0x00000000)
<7> [156.880756] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [156.881067] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1024, found 0)
<7> [156.881346] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 1344, found 0)
<7> [156.881576] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1024, found 0)
<7> [156.881805] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 1344, found 0)
<7> [156.882145] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1048, found 0)
<7> [156.882428] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 1184, found 0)
<7> [156.882667] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 768, found 0)
<7> [156.883404] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 768, found 0)
<7> [156.883685] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 771, found 0)
<7> [156.883945] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 777, found 0)
<7> [156.884205] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 806, found 0)
<7> [156.884486] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 806, found 0)
<7> [156.884734] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1024, found 0)
<7> [156.885032] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 1344, found 0)
<7> [156.885277] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1024, found 0)
<7> [156.885513] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 1344, found 0)
<7> [156.885797] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1048, found 0)
<7> [156.886154] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 1184, found 0)
<7> [156.886392] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 768, found 0)
<7> [156.886695] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 768, found 0)
<7> [156.886965] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 771, found 0)
<7> [156.887201] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 777, found 0)
<7> [156.887464] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 806, found 0)
<7> [156.887741] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 806, found 0)
<7> [156.888013] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [156.888292] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [156.888548] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [156.888799] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [156.889314] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 64800, found 0)
<7> [156.889564] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 64800, found 0)
<7> [156.889803] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in port_clock (expected 64800, found 0)
<7> [156.890072] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:72:pipe B] fastset requirement not met, forcing full modeset
<7> [156.890325] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:72:pipe B] releasing PCH DPLL A
<7> [156.890585] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:72:pipe B] min cdclk: 72000 kHz -> 0 kHz
<7> [156.890859] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:72:pipe B] enable: no [modeset]
<7> [156.891112] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:54:primary B] fb: [NOFB], visible: no
<7> [156.891453] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe B
<7> [156.892081] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found
<7> [156.892314] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found
<7> [156.892533] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found
<7> [156.909777] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PCH DPLL A (active 0x2, on? 1) for [CRTC:72:pipe B]
<7> [156.910366] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PCH DPLL A
<7> [156.911157] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:CRT]
<7> [156.911684] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:75:HDMI D]
<7> [156.912192] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:83:DP D]
<7> [156.912751] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:73:VGA-1]
<7> [156.913182] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:72:pipe B]
<7> [156.913561] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:53:pipe A]
<7> [156.913589] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:76:HDMI-A-1]
<7> [156.913692] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [156.913958] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:53:pipe A] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [156.914239] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe A, lanes 3
<7> [156.914470] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [156.914707] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [156.914962] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [156.915240] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in has_pch_encoder (expected no, found yes)
<7> [156.915477] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in fdi_lanes (expected 0, found 3)
<7> [156.915713] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in fdi_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6934582/8388608 link 288940/524288)
<7> [156.915976] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [156.916252] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [156.916482] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [156.916711] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [156.916982] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [156.917273] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [156.917503] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [156.917732] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [156.918004] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [156.918282] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [156.918511] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [156.918740] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [156.919012] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [156.919290] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [156.919519] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [156.919748] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [156.920036] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [156.920339] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [156.920568] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [156.920819] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [156.921091] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [156.921326] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [156.921561] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [156.921796] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [156.922066] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [156.922352] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [156.922587] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [156.922849] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [156.923118] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [156.923354] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [156.923589] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [156.923838] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [156.924114] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in dpll_hw_state
<7> [156.924363] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [156.924600] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [156.924869] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [156.925143] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0xc4010001, dpll_md: 0x0, fp0: 0x30f06, fp1: 0x30f06
<7> [156.925372] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [156.925600] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148800)
<7> [156.925875] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148800)
<7> [156.926147] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in port_clock (expected 0, found 223200)
<7> [156.926376] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [156.926617] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006)
<7> [156.926878] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in avi infoframe
<7> [156.927164] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [156.927393] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [156.927620] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [156.927623] i915 0000:00:02.0: colorspace: RGB
<7> [156.927625] i915 0000:00:02.0: scan mode: Underscan
<7> [156.927627] i915 0000:00:02.0: colorimetry: No Data
<7> [156.927629] i915 0000:00:02.0: picture aspect: 16:9
<7> [156.927631] i915 0000:00:02.0: active aspect: Same as Picture
<7> [156.927633] i915 0000:00:02.0: itc: No Data
<7> [156.927635] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [156.927637] i915 0000:00:02.0: quantization range: Default
<7> [156.927639] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [156.927641] i915 0000:00:02.0: video code: 16
<7> [156.927643] i915 0000:00:02.0: ycc quantization range: Full
<7> [156.927645] i915 0000:00:02.0: hdmi content type: Graphics
<7> [156.927646] i915 0000:00:02.0: pixel repeat: 0
<7> [156.927648] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [156.927651] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in spd infoframe
<7> [156.927940] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [156.928228] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [156.928457] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [156.928459] i915 0000:00:02.0: vendor: Intel
<7> [156.928461] i915 0000:00:02.0: product: Integrated gfx
<7> [156.928463] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [156.928465] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hdmi infoframe
<7> [156.928708] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [156.928979] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [156.929251] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [156.929253] i915 0000:00:02.0: empty frame
<7> [156.929256] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:53:pipe A] fastset requirement not met, forcing full modeset
<7> [156.929512] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:53:pipe A] min cdclk: 0 kHz -> 165334 kHz
<7> [156.929741] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:53:pipe A] allocated PCH DPLL A
<7> [156.930012] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:53:pipe A] reserving PCH DPLL A
<7> [156.930274] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:53:pipe A] enable: yes [modeset]
<7> [156.930503] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [156.930744] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 36, dithering: 0
<7> [156.931013] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [156.931024] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [156.931289] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [156.931517] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [156.931745] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [156.932059] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] fdi: lanes: 3; data_m: 6934582, data_n: 8388608, link_m: 288940, link_n: 524288, tu: 64
<7> [156.932311] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [156.932538] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x6
<7> [156.932777] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [156.932779] i915 0000:00:02.0: colorspace: RGB
<7> [156.932781] i915 0000:00:02.0: scan mode: Underscan
<7> [156.932783] i915 0000:00:02.0: colorimetry: No Data
<7> [156.932785] i915 0000:00:02.0: picture aspect: 16:9
<7> [156.932787] i915 0000:00:02.0: active aspect: Same as Picture
<7> [156.932789] i915 0000:00:02.0: itc: No Data
<7> [156.932791] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [156.932807] i915 0000:00:02.0: quantization range: Default
<7> [156.932811] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [156.932815] i915 0000:00:02.0: video code: 16
<7> [156.932819] i915 0000:00:02.0: ycc quantization range: Full
<7> [156.932821] i915 0000:00:02.0: hdmi content type: Graphics
<7> [156.932823] i915 0000:00:02.0: pixel repeat: 0
<7> [156.932825] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [156.932827] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [156.932830] i915 0000:00:02.0: vendor: Intel
<7> [156.932832] i915 0000:00:02.0: product: Integrated gfx
<7> [156.932834] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [156.932836] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [156.932838] i915 0000:00:02.0: empty frame
<7> [156.932841] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 10 00 08 00 65 32 00 01 00 00 00 00 00 00 00 00
<7> [156.933130] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 61 a4 9a 00 4d 69 20 54 56 09 07 07 11 17 50 51
<7> [156.933358] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 07 00 00 00
<7> [156.933586] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1
<7> [156.933848] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [156.934140] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0
<7> [156.934369] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 0, vmax vblank: 0, vmin vtotal: 0, vmax vtotal: 0
<7> [156.934597] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [156.934865] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [156.935143] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [156.935373] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148800, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [156.935602] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 60 148800 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [156.935904] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148800, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [156.936191] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 223200, pipe src: 1920x1080+0+0, pixel rate 148800, min cdclk 165334
<7> [156.936420] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0
<7> [156.936647] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [156.936915] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [156.937191] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0xc4010001, dpll_md: 0x0, fp0: 0x30f06, fp1: 0x30f06
<7> [156.937419] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [156.937647] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [156.937910] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [156.938189] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [156.938417] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [156.938643] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [156.938921] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [156.939204] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:primary A] fb: [FB:90] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [156.939434] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [156.939673] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [156.942294] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [156.942545] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:CRT]
<7> [156.942776] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:75:HDMI D]
<7> [156.943020] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:83:DP D]
<7> [156.943915] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [156.945028] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100
<7> [156.945273] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done.
<7> [156.946201] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x600
<7> [156.946430] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done.
<7> [156.946666] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train done.
<7> [156.946931] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PCH DPLL A (active 0x1, on? 0) for [CRTC:53:pipe A]
<7> [156.947192] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PCH DPLL A
<7> [156.998867] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:76:HDMI-A-1][ENCODER:75:HDMI D] Enable audio codec on [CRTC:53:pipe A], 36 bytes ELD
<7> [157.015517] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] HDMI audio pixel clock setting for 148800 not found, falling back to defaults
<7> [157.016055] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)
<7> [157.016545] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:76:HDMI-A-1]
<7> [157.017120] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:53:pipe A]
<7> [167.768634] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:53:pipe A]
<7> [167.768855] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [167.769486] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [167.770011] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [167.770567] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in has_pch_encoder (expected yes, found no)
<7> [167.771154] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in fdi_lanes (expected 3, found 0)
<7> [167.771672] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in fdi_m_n (expected tu 64 data 6934582/8388608 link 288940/524288, found tu 0, data 0/0 link 0/0)
<7> [167.772255] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [167.772492] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [167.772737] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [167.772981] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [167.773245] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [167.773480] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [167.773716] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [167.773951] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [167.774212] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [167.774448] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [167.774684] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [167.774930] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [167.775179] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [167.775415] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [167.775650] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [167.775884] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [167.776157] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [167.776391] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [167.776627] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [167.776869] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [167.777130] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [167.777366] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [167.777601] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [167.777835] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [167.778080] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [167.778358] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [167.778594] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [167.778829] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [167.779119] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [167.779390] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [167.779620] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [167.779859] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [167.780128] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [167.780390] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148800, found 0)
<7> [167.780619] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148800, found 0)
<7> [167.780848] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in port_clock (expected 223200, found 0)
<7> [167.781124] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [167.781387] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [167.781625] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in avi infoframe
<7> [167.781860] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [167.782135] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [167.782141] i915 0000:00:02.0: colorspace: RGB
<7> [167.782145] i915 0000:00:02.0: scan mode: Underscan
<7> [167.782148] i915 0000:00:02.0: colorimetry: No Data
<7> [167.782151] i915 0000:00:02.0: picture aspect: 16:9
<7> [167.782153] i915 0000:00:02.0: active aspect: Same as Picture
<7> [167.782155] i915 0000:00:02.0: itc: No Data
<7> [167.782157] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [167.782159] i915 0000:00:02.0: quantization range: Default
<7> [167.782161] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [167.782163] i915 0000:00:02.0: video code: 16
<7> [167.782165] i915 0000:00:02.0: ycc quantization range: Full
<7> [167.782174] i915 0000:00:02.0: hdmi content type: Graphics
<7> [167.782176] i915 0000:00:02.0: pixel repeat: 0
<7> [167.782178] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [167.782181] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [167.782423] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in spd infoframe
<7> [167.782652] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [167.782878] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [167.782881] i915 0000:00:02.0: vendor: Intel
<7> [167.782882] i915 0000:00:02.0: product: Integrated gfx
<7> [167.782884] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [167.782887] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [167.783160] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hdmi infoframe
<7> [167.783399] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [167.783627] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [167.783629] i915 0000:00:02.0: empty frame
<7> [167.783631] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [167.783860] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:53:pipe A] fastset requirement not met, forcing full modeset
<7> [167.784131] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:53:pipe A] releasing PCH DPLL A
<7> [167.784405] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:53:pipe A] min cdclk: 165334 kHz -> 0 kHz
<7> [167.784648] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:53:pipe A] enable: no [modeset]
<7> [167.784905] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:primary A] fb: [NOFB], visible: no
<7> [167.785215] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:76:HDMI-A-1][ENCODER:75:HDMI D] Disable audio codec on [CRTC:53:pipe A]
<7> [167.810278] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [167.828598] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found
<7> [167.829092] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found
<7> [167.829608] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found
<7> [167.838747] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PCH DPLL A (active 0x1, on? 1) for [CRTC:53:pipe A]
<7> [167.838984] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PCH DPLL A
<7> [167.839429] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:CRT]
<7> [167.839688] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:75:HDMI D]
<7> [167.839932] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:83:DP D]
<7> [167.840168] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:76:HDMI-A-1]
<7> [167.840442] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:53:pipe A]
<7> [167.840827] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:72:pipe B]
<7> [167.840965] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:53:pipe A]
<7> [167.840993] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:76:HDMI-A-1]
<7> [167.841084] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [167.841344] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:53:pipe A] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [167.841612] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe A, lanes 3
<7> [167.841870] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [167.842112] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [167.842348] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [167.842633] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in has_pch_encoder (expected no, found yes)
<7> [167.842881] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in fdi_lanes (expected 0, found 3)
<7> [167.843110] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in fdi_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6934582/8388608 link 288940/524288)
<7> [167.843353] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [167.843631] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [167.843867] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [167.844102] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [167.844337] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [167.844582] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [167.844818] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [167.845052] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [167.845287] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [167.845536] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [167.845772] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [167.846007] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [167.846242] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [167.846494] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [167.846730] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [167.846965] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [167.847200] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [167.847446] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [167.847682] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [167.847916] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [167.848151] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [167.848400] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [167.848636] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [167.848870] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [167.849105] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [167.849339] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [167.849592] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [167.849827] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [167.850062] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [167.850297] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [167.850541] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [167.850777] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [167.851012] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [167.851247] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148800)
<7> [167.851499] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148800)
<7> [167.851824] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in port_clock (expected 0, found 223200)
<7> [167.852158] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [167.852444] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006)
<7> [167.852682] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in avi infoframe
<7> [167.852993] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [167.853290] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [167.853573] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [167.853578] i915 0000:00:02.0: colorspace: RGB
<7> [167.853582] i915 0000:00:02.0: scan mode: Underscan
<7> [167.853585] i915 0000:00:02.0: colorimetry: No Data
<7> [167.853589] i915 0000:00:02.0: picture aspect: 16:9
<7> [167.853592] i915 0000:00:02.0: active aspect: Same as Picture
<7> [167.853596] i915 0000:00:02.0: itc: No Data
<7> [167.853600] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [167.853604] i915 0000:00:02.0: quantization range: Default
<7> [167.853608] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [167.853612] i915 0000:00:02.0: video code: 16
<7> [167.853615] i915 0000:00:02.0: ycc quantization range: Full
<7> [167.853619] i915 0000:00:02.0: hdmi content type: Graphics
<7> [167.853623] i915 0000:00:02.0: pixel repeat: 0
<7> [167.853627] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [167.853631] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in spd infoframe
<7> [167.853986] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [167.854327] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [167.854589] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [167.854592] i915 0000:00:02.0: vendor: Intel
<7> [167.854595] i915 0000:00:02.0: product: Integrated gfx
<7> [167.854597] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [167.854599] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hdmi infoframe
<7> [167.854922] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [167.855279] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [167.855604] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [167.855618] i915 0000:00:02.0: empty frame
<7> [167.855621] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:53:pipe A] fastset requirement not met, forcing full modeset
<7> [167.855889] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:53:pipe A] min cdclk: 0 kHz -> 165334 kHz
<7> [167.856215] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:53:pipe A] allocated PCH DPLL A
<7> [167.856559] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:53:pipe A] reserving PCH DPLL A
<7> [167.856810] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:53:pipe A] enable: yes [modeset]
<7> [167.857153] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [167.857422] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 36, dithering: 0
<7> [167.857719] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [167.858016] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [167.858248] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [167.858624] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [167.858632] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [167.858874] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] fdi: lanes: 3; data_m: 6934582, data_n: 8388608, link_m: 288940, link_n: 524288, tu: 64
<7> [167.859110] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [167.859484] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x6
<7> [167.859775] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [167.859778] i915 0000:00:02.0: colorspace: RGB
<7> [167.859780] i915 0000:00:02.0: scan mode: Underscan
<7> [167.859781] i915 0000:00:02.0: colorimetry: No Data
<7> [167.859783] i915 0000:00:02.0: picture aspect: 16:9
<7> [167.859785] i915 0000:00:02.0: active aspect: Same as Picture
<7> [167.859787] i915 0000:00:02.0: itc: No Data
<7> [167.859789] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [167.859791] i915 0000:00:02.0: quantization range: Default
<7> [167.859793] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [167.859795] i915 0000:00:02.0: video code: 16
<7> [167.859797] i915 0000:00:02.0: ycc quantization range: Full
<7> [167.859798] i915 0000:00:02.0: hdmi content type: Graphics
<7> [167.859800] i915 0000:00:02.0: pixel repeat: 0
<7> [167.859802] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [167.859804] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [167.859806] i915 0000:00:02.0: vendor: Intel
<7> [167.859808] i915 0000:00:02.0: product: Integrated gfx
<7> [167.859810] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [167.859812] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [167.859815] i915 0000:00:02.0: empty frame
<7> [167.859817] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 10 00 08 00 65 32 00 01 00 00 00 00 00 00 00 00
<7> [167.860046] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 61 a4 9a 00 4d 69 20 54 56 09 07 07 11 17 50 51
<7> [167.860402] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 07 00 00 00
<7> [167.860768] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1
<7> [167.861085] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [167.861369] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0
<7> [167.861648] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 0, vmax vblank: 0, vmin vtotal: 0, vmax vtotal: 0
<7> [167.861916] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [167.862281] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [167.862545] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [167.862808] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148800, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [167.863132] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 60 148800 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [167.863402] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148800, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [167.863698] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 223200, pipe src: 1920x1080+0+0, pixel rate 148800, min cdclk 165334
<7> [167.864001] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0
<7> [167.864231] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [167.864592] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [167.864938] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0xc4010001, dpll_md: 0x0, fp0: 0x30f06, fp1: 0x30f06
<7> [167.865183] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [167.865477] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [167.865818] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [167.866078] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [167.866372] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [167.866709] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [167.866939] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [167.867254] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:primary A] fb: [FB:90] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [167.867567] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [167.867796] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [167.868170] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [167.868459] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:CRT]
<7> [167.868808] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:75:HDMI D]
<7> [167.869039] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:83:DP D]
<7> [167.870008] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [167.871161] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100
<7> [167.871430] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done.
<7> [167.872403] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x600
<7> [167.872664] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done.
<7> [167.872889] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train done.
<7> [167.873253] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PCH DPLL A (active 0x1, on? 0) for [CRTC:53:pipe A]
<7> [167.873540] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PCH DPLL A
<7> [167.925284] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:76:HDMI-A-1][ENCODER:75:HDMI D] Enable audio codec on [CRTC:53:pipe A], 36 bytes ELD
<7> [167.941889] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] HDMI audio pixel clock setting for 148800 not found, falling back to defaults
<7> [167.942381] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)
<7> [167.942931] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:76:HDMI-A-1]
<7> [167.943726] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:53:pipe A]
<6> [175.759878] [IGT] kms_flip: finished subtest A-HDMI-A1, FAIL
Created at 2026-03-24 19:29:45