Results for igt@kms_flip@wf_vblank-ts-check@c-hdmi-a1

Machine description: shard-tglu-6

Result: Fail

git-log-oneline i915_display_info7 igt_runner7 runtimes7 results7.json results7-i915-load.json i915_display_info_post_exec7 boot7 dmesg7

DetailValue
Duration 11.05 seconds
Hostname 
shard-tglu-6
Igt-Version 
IGT-Version: 1.30-g61cac4a58 (x86_64) (Linux: 6.14.0-rc5-CI_DRM_16247-g23e0800ae084+ x86_64)
Out 
Starting dynamic subtest: C-HDMI-A1
  1920x1080: 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5 
Expected frametime: 8335us; measured 8274.1us +- 3.403us accuracy 0.12%
vblank interval differs from modeline! expected 8334.6us, measured 8274us +- 3.403us, difference 60.4us (17.8 sigma)
Stack trace:
  #0 ../lib/igt_core.c:2055 __igt_fail_assert()
  #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1845 run_test()
  #3 ../tests/kms_flip.c:2078 __igt_unique____real_main2001()
  #4 ../tests/kms_flip.c:2001 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest C-HDMI-A1: FAIL (11.049s)
Err 
Starting dynamic subtest: C-HDMI-A1
(kms_flip:2011) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:2011) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
Dynamic subtest C-HDMI-A1 failed.
**** DEBUG ****
(kms_flip:2011) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x0, size=0)
(kms_flip:2011) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=5, pitch=7680)
(kms_flip:2011) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:2011) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x0, size=0)
(kms_flip:2011) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=6, pitch=7680)
(kms_flip:2011) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:2011) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2011) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2011) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS
(kms_flip:2011) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2011) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2011) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS
(kms_flip:2011) igt_kms-INFO:   1920x1080: 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5 
(kms_flip:2011) DEBUG: No stale events found
(kms_flip:2011) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:2011) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:2011) INFO: Expected frametime: 8335us; measured 8274.1us +- 3.403us accuracy 0.12%
(kms_flip:2011) INFO: vblank interval differs from modeline! expected 8334.6us, measured 8274us +- 3.403us, difference 60.4us (17.8 sigma)
(kms_flip:2011) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 60.304371
current_received_ts = 60.304241
current_seq = 32
count = 0
seq_step = 10
(kms_flip:2011) DEBUG: name = vblank
last_ts = 60.304371
last_received_ts = 60.304241
last_seq = 32
current_ts = 60.387112
current_received_ts = 60.387093
current_seq = 42
count = 1
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 60.304371/32, current 60.387112/42: elapsed=82742.0us expected=82741.2us +- 413.7us, error 0.0%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 60.387112
last_received_ts = 60.387093
last_seq = 42
current_ts = 60.469852
current_received_ts = 60.469685
current_seq = 52
count = 2
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 60.387112/42, current 60.469852/52: elapsed=82741.0us expected=82741.2us +- 413.7us, error 0.0%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 60.469852
last_received_ts = 60.469685
last_seq = 52
current_ts = 60.552593
current_received_ts = 60.552471
current_seq = 62
count = 3
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 60.469852/52, current 60.552593/62: elapsed=82741.0us expected=82741.2us +- 413.7us, error 0.0%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 60.552593
last_received_ts = 60.552471
last_seq = 62
current_ts = 60.635330
current_received_ts = 60.635162
current_seq = 72
count = 4
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 60.552593/62, current 60.635330/72: elapsed=82738.0us expected=82741.2us +- 413.7us, error 0.0%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 60.635330
last_received_ts = 60.635162
last_seq = 72
current_ts = 60.718075
current_received_ts = 60.717937
current_seq = 82
count = 5
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 60.635330/72, current 60.718075/82: elapsed=82743.0us expected=82741.2us +- 413.7us, error 0.0%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 60.718075
last_received_ts = 60.717937
last_seq = 82
current_ts = 60.800819
current_received_ts = 60.800655
current_seq = 92
count = 6
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 60.718075/82, current 60.800819/92: elapsed=82744.0us expected=82741.2us +- 413.7us, error 0.0%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 60.800819
last_received_ts = 60.800655
last_seq = 92
current_ts = 60.883591
current_received_ts = 60.883427
current_seq = 102
count = 7
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 60.800819/92, current 60.883591/102: elapsed=82771.0us expected=82741.2us +- 413.7us, error 0.0%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 60.883591
last_received_ts = 60.883427
last_seq = 102
current_ts = 60.966400
current_received_ts = 60.966118
current_seq = 112
count = 8
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 60.883591/102, current 60.966400/112: elapsed=82810.0us expected=82741.2us +- 413.7us, error 0.1%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 60.966400
last_received_ts = 60.966118
last_seq = 112
current_ts = 61.049225
current_received_ts = 61.049129
current_seq = 122
count = 9
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 60.966400/112, current 61.049225/122: elapsed=82823.0us expected=82741.2us +- 413.7us, error 0.1%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 61.049225
last_received_ts = 61.049129
last_seq = 122
current_ts = 61.132038
current_received_ts = 61.131866
current_seq = 132
count = 10
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 61.049225/122, current 61.132038/132: elapsed=82817.0us expected=82741.2us +- 413.7us, error 0.1%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 61.132038
last_received_ts = 61.131866
last_seq = 132
current_ts = 61.214855
current_received_ts = 61.214790
current_seq = 142
count = 11
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 61.132038/132, current 61.214855/142: elapsed=82815.0us expected=82741.2us +- 413.7us, error 0.1%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 61.214855
last_received_ts = 61.214790
last_seq = 142
current_ts = 61.297668
current_received_ts = 61.297577
current_seq = 152
count = 12
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 61.214855/142, current 61.297668/152: elapsed=82815.0us expected=82741.2us +- 413.7us, error 0.1%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 61.297668
last_received_ts = 61.297577
last_seq = 152
current_ts = 61.380486
current_received_ts = 61.380314
current_seq = 162
count = 13
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 61.297668/152, current 61.380486/162: elapsed=82817.0us expected=82741.2us +- 413.7us, error 0.1%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 61.380486
last_received_ts = 61.380314
last_seq = 162
current_ts = 61.463303
current_received_ts = 61.463146
current_seq = 172
count = 14
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 61.380486/162, current 61.463303/172: elapsed=82815.0us expected=82741.2us +- 413.7us, error 0.1%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 61.463303
last_received_ts = 61.463146
last_seq = 172
current_ts = 61.546120
current_received_ts = 61.545956
current_seq = 182
count = 15
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 61.463303/172, current 61.546120/182: elapsed=82819.0us expected=82741.2us +- 413.7us, error 0.1%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 61.546120
last_received_ts = 61.545956
last_seq = 182
current_ts = 61.628937
current_received_ts = 61.628777
current_seq = 192
count = 16
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 61.546120/182, current 61.628937/192: elapsed=82815.0us expected=82741.2us +- 413.7us, error 0.1%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 61.628937
last_received_ts = 61.628777
last_seq = 192
current_ts = 61.711750
current_received_ts = 61.711578
current_seq = 202
count = 17
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 61.628937/192, current 61.711750/202: elapsed=82815.0us expected=82741.2us +- 413.7us, error 0.1%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 61.711750
last_received_ts = 61.711578
last_seq = 202
current_ts = 61.794571
current_received_ts = 61.794411
current_seq = 212
count = 18
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 61.711750/202, current 61.794571/212: elapsed=82821.0us expected=82741.2us +- 413.7us, error 0.1%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 61.794571
last_received_ts = 61.794411
last_seq = 212
current_ts = 61.877411
current_received_ts = 61.877251
current_seq = 222
count = 19
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 61.794571/212, current 61.877411/222: elapsed=82837.0us expected=82741.2us +- 413.7us, error 0.1%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 61.877411
last_received_ts = 61.877251
last_seq = 222
current_ts = 61.960293
current_received_ts = 61.960186
current_seq = 232
count = 20
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 61.877411/222, current 61.960293/232: elapsed=82883.0us expected=82741.2us +- 413.7us, error 0.2%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 61.960293
last_received_ts = 61.960186
last_seq = 232
current_ts = 62.043179
current_received_ts = 62.043064
current_seq = 242
count = 21
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 61.960293/232, current 62.043179/242: elapsed=82887.0us expected=82741.2us +- 413.7us, error 0.2%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 62.043179
last_received_ts = 62.043064
last_seq = 242
current_ts = 62.126060
current_received_ts = 62.125904
current_seq = 252
count = 22
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 62.043179/242, current 62.126060/252: elapsed=82882.0us expected=82741.2us +- 413.7us, error 0.2%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 62.126060
last_received_ts = 62.125904
last_seq = 252
current_ts = 62.208942
current_received_ts = 62.208881
current_seq = 262
count = 23
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 62.126060/252, current 62.208942/262: elapsed=82880.0us expected=82741.2us +- 413.7us, error 0.2%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 62.208942
last_received_ts = 62.208881
last_seq = 262
current_ts = 62.291824
current_received_ts = 62.291664
current_seq = 272
count = 24
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 62.208942/262, current 62.291824/272: elapsed=82883.0us expected=82741.2us +- 413.7us, error 0.2%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 62.291824
last_received_ts = 62.291664
last_seq = 272
current_ts = 62.374710
current_received_ts = 62.374519
current_seq = 282
count = 25
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 62.291824/272, current 62.374710/282: elapsed=82887.0us expected=82741.2us +- 413.7us, error 0.2%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 62.374710
last_received_ts = 62.374519
last_seq = 282
current_ts = 62.457588
current_received_ts = 62.457432
current_seq = 292
count = 26
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 62.374710/282, current 62.457588/292: elapsed=82878.0us expected=82741.2us +- 413.7us, error 0.2%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 62.457588
last_received_ts = 62.457432
last_seq = 292
current_ts = 62.540474
current_received_ts = 62.540314
current_seq = 302
count = 27
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 62.457588/292, current 62.540474/302: elapsed=82884.0us expected=82741.2us +- 413.7us, error 0.2%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 62.540474
last_received_ts = 62.540314
last_seq = 302
current_ts = 62.623356
current_received_ts = 62.623226
current_seq = 312
count = 28
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 62.540474/302, current 62.623356/312: elapsed=82881.0us expected=82741.2us +- 413.7us, error 0.2%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 62.623356
last_received_ts = 62.623226
last_seq = 312
current_ts = 62.706238
current_received_ts = 62.706100
current_seq = 322
count = 29
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 62.623356/312, current 62.706238/322: elapsed=82884.0us expected=82741.2us +- 413.7us, error 0.2%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 62.706238
last_received_ts = 62.706100
last_seq = 322
current_ts = 62.789124
current_received_ts = 62.788967
current_seq = 332
count = 30
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 62.706238/322, current 62.789124/332: elapsed=82885.0us expected=82741.2us +- 413.7us, error 0.2%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 62.789124
last_received_ts = 62.788967
last_seq = 332
current_ts = 62.872021
current_received_ts = 62.871868
current_seq = 342
count = 31
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 62.789124/332, current 62.872021/342: elapsed=82898.0us expected=82741.2us +- 413.7us, error 0.2%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 62.872021
last_received_ts = 62.871868
last_seq = 342
current_ts = 62.954964
current_received_ts = 62.954811
current_seq = 352
count = 32
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 62.872021/342, current 62.954964/352: elapsed=82944.0us expected=82741.2us +- 413.7us, error 0.2%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 62.954964
last_received_ts = 62.954811
last_seq = 352
current_ts = 63.037903
current_received_ts = 63.037788
current_seq = 362
count = 33
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 62.954964/352, current 63.037903/362: elapsed=82938.0us expected=82741.2us +- 413.7us, error 0.2%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 63.037903
last_received_ts = 63.037788
last_seq = 362
current_ts = 63.120838
current_received_ts = 63.120708
current_seq = 372
count = 34
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 63.037903/362, current 63.120838/372: elapsed=82937.0us expected=82741.2us +- 413.7us, error 0.2%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 63.120838
last_received_ts = 63.120708
last_seq = 372
current_ts = 63.203785
current_received_ts = 63.203625
current_seq = 382
count = 35
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 63.120838/372, current 63.203785/382: elapsed=82944.0us expected=82741.2us +- 413.7us, error 0.2%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 63.203785
last_received_ts = 63.203625
last_seq = 382
current_ts = 63.286724
current_received_ts = 63.286530
current_seq = 392
count = 36
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 63.203785/382, current 63.286724/392: elapsed=82939.0us expected=82741.2us +- 413.7us, error 0.2%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 63.286724
last_received_ts = 63.286530
last_seq = 392
current_ts = 63.369663
current_received_ts = 63.369511
current_seq = 402
count = 37
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 63.286724/392, current 63.369663/402: elapsed=82941.0us expected=82741.2us +- 413.7us, error 0.2%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 63.369663
last_received_ts = 63.369511
last_seq = 402
current_ts = 63.452606
current_received_ts = 63.452438
current_seq = 412
count = 38
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 63.369663/402, current 63.452606/412: elapsed=82942.0us expected=82741.2us +- 413.7us, error 0.2%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 63.452606
last_received_ts = 63.452438
last_seq = 412
current_ts = 63.535545
current_received_ts = 63.535385
current_seq = 422
count = 39
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 63.452606/412, current 63.535545/422: elapsed=82938.0us expected=82741.2us +- 413.7us, error 0.2%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 63.535545
last_received_ts = 63.535385
last_seq = 422
current_ts = 63.618488
current_received_ts = 63.618340
current_seq = 432
count = 40
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 63.535545/422, current 63.618488/432: elapsed=82946.0us expected=82741.2us +- 413.7us, error 0.2%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 63.618488
last_received_ts = 63.618340
last_seq = 432
current_ts = 63.701424
current_received_ts = 63.701271
current_seq = 442
count = 41
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 63.618488/432, current 63.701424/442: elapsed=82934.0us expected=82741.2us +- 413.7us, error 0.2%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 63.701424
last_received_ts = 63.701271
last_seq = 442
current_ts = 63.784370
current_received_ts = 63.784199
current_seq = 452
count = 42
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 63.701424/442, current 63.784370/452: elapsed=82947.0us expected=82741.2us +- 413.7us, error 0.2%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 63.784370
last_received_ts = 63.784199
last_seq = 452
current_ts = 63.867317
current_received_ts = 63.867115
current_seq = 462
count = 43
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 63.784370/452, current 63.867317/462: elapsed=82947.0us expected=82741.2us +- 413.7us, error 0.2%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 63.867317
last_received_ts = 63.867115
last_seq = 462
current_ts = 63.950310
current_received_ts = 63.950188
current_seq = 472
count = 44
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 63.867317/462, current 63.950310/472: elapsed=82992.0us expected=82741.2us +- 413.7us, error 0.3%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 63.950310
last_received_ts = 63.950188
last_seq = 472
current_ts = 64.033302
current_received_ts = 64.033134
current_seq = 482
count = 45
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 63.950310/472, current 64.033302/482: elapsed=82993.0us expected=82741.2us +- 413.7us, error 0.3%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 64.033302
last_received_ts = 64.033134
last_seq = 482
current_ts = 64.116295
current_received_ts = 64.116119
current_seq = 492
count = 46
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 64.033302/482, current 64.116295/492: elapsed=82990.0us expected=82741.2us +- 413.7us, error 0.3%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 64.116295
last_received_ts = 64.116119
last_seq = 492
current_ts = 64.199287
current_received_ts = 64.199120
current_seq = 502
count = 47
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 64.116295/492, current 64.199287/502: elapsed=82994.0us expected=82741.2us +- 413.7us, error 0.3%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 64.199287
last_received_ts = 64.199120
last_seq = 502
current_ts = 64.282272
current_received_ts = 64.282104
current_seq = 512
count = 48
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 64.199287/502, current 64.282272/512: elapsed=82989.0us expected=82741.2us +- 413.7us, error 0.3%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 64.282272
last_received_ts = 64.282104
last_seq = 512
current_ts = 64.365265
current_received_ts = 64.365143
current_seq = 522
count = 49
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 64.282272/512, current 64.365265/522: elapsed=82988.0us expected=82741.2us +- 413.7us, error 0.3%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 64.365265
last_received_ts = 64.365143
last_seq = 522
current_ts = 64.448257
current_received_ts = 64.448105
current_seq = 532
count = 50
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 64.365265/522, current 64.448257/532: elapsed=82997.0us expected=82741.2us +- 413.7us, error 0.3%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 64.448257
last_received_ts = 64.448105
last_seq = 532
current_ts = 64.531250
current_received_ts = 64.531120
current_seq = 542
count = 51
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 64.448257/532, current 64.531250/542: elapsed=82991.0us expected=82741.2us +- 413.7us, error 0.3%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 64.531250
last_received_ts = 64.531120
last_seq = 542
current_ts = 64.614243
current_received_ts = 64.614120
current_seq = 552
count = 52
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 64.531250/542, current 64.614243/552: elapsed=82991.0us expected=82741.2us +- 413.7us, error 0.3%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 64.614243
last_received_ts = 64.614120
last_seq = 552
current_ts = 64.697235
current_received_ts = 64.697105
current_seq = 562
count = 53
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 64.614243/552, current 64.697235/562: elapsed=82990.0us expected=82741.2us +- 413.7us, error 0.3%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 64.697235
last_received_ts = 64.697105
last_seq = 562
current_ts = 64.780220
current_received_ts = 64.780174
current_seq = 572
count = 54
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 64.697235/562, current 64.780220/572: elapsed=82988.0us expected=82741.2us +- 413.7us, error 0.3%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 64.780220
last_received_ts = 64.780174
last_seq = 572
current_ts = 64.863220
current_received_ts = 64.863098
current_seq = 582
count = 55
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 64.780220/572, current 64.863220/582: elapsed=83003.0us expected=82741.2us +- 413.7us, error 0.3%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 64.863220
last_received_ts = 64.863098
last_seq = 582
current_ts = 64.946259
current_received_ts = 64.946106
current_seq = 592
count = 56
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 64.863220/582, current 64.946259/592: elapsed=83036.0us expected=82741.2us +- 413.7us, error 0.4%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 64.946259
last_received_ts = 64.946106
last_seq = 592
current_ts = 65.029297
current_received_ts = 65.029137
current_seq = 602
count = 57
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 64.946259/592, current 65.029297/602: elapsed=83035.0us expected=82741.2us +- 413.7us, error 0.4%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 65.029297
last_received_ts = 65.029137
last_seq = 602
current_ts = 65.112328
current_received_ts = 65.112274
current_seq = 612
count = 58
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 65.029297/602, current 65.112328/612: elapsed=83032.0us expected=82741.2us +- 413.7us, error 0.4%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 65.112328
last_received_ts = 65.112274
last_seq = 612
current_ts = 65.195366
current_received_ts = 65.195251
current_seq = 622
count = 59
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 65.112328/612, current 65.195366/622: elapsed=83038.0us expected=82741.2us +- 413.7us, error 0.4%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 65.195366
last_received_ts = 65.195251
last_seq = 622
current_ts = 65.278397
current_received_ts = 65.278236
current_seq = 632
count = 60
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 65.195366/622, current 65.278397/632: elapsed=83034.0us expected=82741.2us +- 413.7us, error 0.4%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 65.278397
last_received_ts = 65.278236
last_seq = 632
current_ts = 65.361443
current_received_ts = 65.361275
current_seq = 642
count = 61
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 65.278397/632, current 65.361443/642: elapsed=83040.0us expected=82741.2us +- 413.7us, error 0.4%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 65.361443
last_received_ts = 65.361275
last_seq = 642
current_ts = 65.444473
current_received_ts = 65.444321
current_seq = 652
count = 62
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 65.361443/642, current 65.444473/652: elapsed=83035.0us expected=82741.2us +- 413.7us, error 0.4%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 65.444473
last_received_ts = 65.444321
last_seq = 652
current_ts = 65.527504
current_received_ts = 65.527283
current_seq = 662
count = 63
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 65.444473/652, current 65.527504/662: elapsed=83033.0us expected=82741.2us +- 413.7us, error 0.4%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 65.527504
last_received_ts = 65.527283
last_seq = 662
current_ts = 65.610542
current_received_ts = 65.610390
current_seq = 672
count = 64
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 65.527504/662, current 65.610542/672: elapsed=83034.0us expected=82741.2us +- 413.7us, error 0.4%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 65.610542
last_received_ts = 65.610390
last_seq = 672
current_ts = 65.693573
current_received_ts = 65.693420
current_seq = 682
count = 65
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 65.610542/672, current 65.693573/682: elapsed=83034.0us expected=82741.2us +- 413.7us, error 0.4%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 65.693573
last_received_ts = 65.693420
last_seq = 682
current_ts = 65.776611
current_received_ts = 65.776413
current_seq = 692
count = 66
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 65.693573/682, current 65.776611/692: elapsed=83039.0us expected=82741.2us +- 413.7us, error 0.4%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 65.776611
last_received_ts = 65.776413
last_seq = 692
current_ts = 65.859650
current_received_ts = 65.859489
current_seq = 702
count = 67
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 65.776611/692, current 65.859650/702: elapsed=83038.0us expected=82741.2us +- 413.7us, error 0.4%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 65.859650
last_received_ts = 65.859489
last_seq = 702
current_ts = 65.942734
current_received_ts = 65.942513
current_seq = 712
count = 68
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 65.859650/702, current 65.942734/712: elapsed=83079.0us expected=82741.2us +- 413.7us, error 0.4%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 65.942734
last_received_ts = 65.942513
last_seq = 712
current_ts = 66.025803
current_received_ts = 66.025581
current_seq = 722
count = 69
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 65.942734/712, current 66.025803/722: elapsed=83071.0us expected=82741.2us +- 413.7us, error 0.4%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 66.025803
last_received_ts = 66.025581
last_seq = 722
current_ts = 66.108879
current_received_ts = 66.108711
current_seq = 732
count = 70
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 66.025803/722, current 66.108879/732: elapsed=83079.0us expected=82741.2us +- 413.7us, error 0.4%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 66.108879
last_received_ts = 66.108711
last_seq = 732
current_ts = 66.191956
current_received_ts = 66.191811
current_seq = 742
count = 71
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 66.108879/732, current 66.191956/742: elapsed=83072.0us expected=82741.2us +- 413.7us, error 0.4%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 66.191956
last_received_ts = 66.191811
last_seq = 742
current_ts = 66.275024
current_received_ts = 66.274956
current_seq = 752
count = 72
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 66.191956/742, current 66.275024/752: elapsed=83070.0us expected=82741.2us +- 413.7us, error 0.4%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 66.275024
last_received_ts = 66.274956
last_seq = 752
current_ts = 66.358101
current_received_ts = 66.358070
current_seq = 762
count = 73
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 66.275024/752, current 66.358101/762: elapsed=83079.0us expected=82741.2us +- 413.7us, error 0.4%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 66.358101
last_received_ts = 66.358070
last_seq = 762
current_ts = 66.441170
current_received_ts = 66.441124
current_seq = 772
count = 74
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 66.358101/762, current 66.441170/772: elapsed=83069.0us expected=82741.2us +- 413.7us, error 0.4%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 66.441170
last_received_ts = 66.441124
last_seq = 772
current_ts = 66.524246
current_received_ts = 66.524101
current_seq = 782
count = 75
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 66.441170/772, current 66.524246/782: elapsed=83078.0us expected=82741.2us +- 413.7us, error 0.4%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 66.524246
last_received_ts = 66.524101
last_seq = 782
current_ts = 66.607323
current_received_ts = 66.607185
current_seq = 792
count = 76
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 66.524246/782, current 66.607323/792: elapsed=83074.0us expected=82741.2us +- 413.7us, error 0.4%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 66.607323
last_received_ts = 66.607185
last_seq = 792
current_ts = 66.690399
current_received_ts = 66.690079
current_seq = 802
count = 77
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 66.607323/792, current 66.690399/802: elapsed=83074.0us expected=82741.2us +- 413.7us, error 0.4%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 66.690399
last_received_ts = 66.690079
last_seq = 802
current_ts = 66.773468
current_received_ts = 66.773102
current_seq = 812
count = 78
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 66.690399/802, current 66.773468/812: elapsed=83074.0us expected=82741.2us +- 413.7us, error 0.4%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 66.773468
last_received_ts = 66.773102
last_seq = 812
current_ts = 66.856552
current_received_ts = 66.856163
current_seq = 822
count = 79
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 66.773468/812, current 66.856552/822: elapsed=83078.0us expected=82741.2us +- 413.7us, error 0.4%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 66.856552
last_received_ts = 66.856163
last_seq = 822
current_ts = 66.939659
current_received_ts = 66.939308
current_seq = 832
count = 80
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 66.856552/822, current 66.939659/832: elapsed=83110.0us expected=82741.2us +- 413.7us, error 0.4%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 66.939659
last_received_ts = 66.939308
last_seq = 832
current_ts = 67.022766
current_received_ts = 67.022369
current_seq = 842
count = 81
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 66.939659/832, current 67.022766/842: elapsed=83106.0us expected=82741.2us +- 413.7us, error 0.4%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 67.022766
last_received_ts = 67.022369
last_seq = 842
current_ts = 67.105873
current_received_ts = 67.105499
current_seq = 852
count = 82
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 67.022766/842, current 67.105873/852: elapsed=83110.0us expected=82741.2us +- 413.7us, error 0.4%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 67.105873
last_received_ts = 67.105499
last_seq = 852
current_ts = 67.188988
current_received_ts = 67.188599
current_seq = 862
count = 83
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 67.105873/852, current 67.188988/862: elapsed=83110.0us expected=82741.2us +- 413.7us, error 0.4%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 67.188988
last_received_ts = 67.188599
last_seq = 862
current_ts = 67.272095
current_received_ts = 67.271736
current_seq = 872
count = 84
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 67.188988/862, current 67.272095/872: elapsed=83112.0us expected=82741.2us +- 413.7us, error 0.4%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 67.272095
last_received_ts = 67.271736
last_seq = 872
current_ts = 67.355202
current_received_ts = 67.354851
current_seq = 882
count = 85
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 67.272095/872, current 67.355202/882: elapsed=83101.0us expected=82741.2us +- 413.7us, error 0.4%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 67.355202
last_received_ts = 67.354851
last_seq = 882
current_ts = 67.438309
current_received_ts = 67.437927
current_seq = 892
count = 86
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 67.355202/882, current 67.438309/892: elapsed=83110.0us expected=82741.2us +- 413.7us, error 0.4%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 67.438309
last_received_ts = 67.437927
last_seq = 892
current_ts = 67.521416
current_received_ts = 67.521065
current_seq = 902
count = 87
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 67.438309/892, current 67.521416/902: elapsed=83110.0us expected=82741.2us +- 413.7us, error 0.4%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 67.521416
last_received_ts = 67.521065
last_seq = 902
current_ts = 67.604530
current_received_ts = 67.604156
current_seq = 912
count = 88
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 67.521416/902, current 67.604530/912: elapsed=83109.0us expected=82741.2us +- 413.7us, error 0.4%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 67.604530
last_received_ts = 67.604156
last_seq = 912
current_ts = 67.687637
current_received_ts = 67.687279
current_seq = 922
count = 89
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 67.604530/912, current 67.687637/922: elapsed=83109.0us expected=82741.2us +- 413.7us, error 0.4%
(kms_flip:2011) DEBUG: name = vblank
last_ts = 67.687637
last_received_ts = 67.687279
last_seq = 922
current_ts = 67.770744
current_received_ts = 67.770386
current_seq = 932
count = 90
seq_step = 10
(kms_flip:2011) DEBUG: vblank ts/seq: last 67.687637/922, current 67.770744/932: elapsed=83105.0us expected=82741.2us +- 413.7us, error 0.4%
(kms_flip:2011) DEBUG: expected 912, counted 910, encoder type 2
(kms_flip:2011) DEBUG: dropped frames, expected 912, counted 910, encoder type 2
(kms_flip:2011) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:2011) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
(kms_flip:2011) igt_core-INFO: Stack trace:
(kms_flip:2011) igt_core-INFO:   #0 ../lib/igt_core.c:2055 __igt_fail_assert()
(kms_flip:2011) igt_core-INFO:   #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0()
(kms_flip:2011) igt_core-INFO:   #2 ../tests/kms_flip.c:1845 run_test()
(kms_flip:2011) igt_core-INFO:   #3 ../tests/kms_flip.c:2078 __igt_unique____real_main2001()
(kms_flip:2011) igt_core-INFO:   #4 ../tests/kms_flip.c:2001 main()
(kms_flip:2011) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2011) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2011) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest C-HDMI-A1: FAIL (11.049s)
Dmesg
<6> [60.234259] [IGT] kms_flip: starting dynamic subtest C-HDMI-A1
<7> [60.234602] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:366]
<7> [60.234658] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:367]
<7> [60.274202] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:100:pipe A]
<7> [60.274496] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:170:pipe B]
<7> [60.274581] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [60.274878] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [60.275157] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [60.275383] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [60.275603] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [60.275849] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [60.276118] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [60.276339] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2080, found 0)
<7> [60.276557] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [60.276794] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2080, found 0)
<7> [60.277038] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1968, found 0)
<7> [60.277253] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2000, found 0)
<7> [60.277468] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [60.277697] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [60.277928] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [60.278094] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [60.278259] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1144, found 0)
<7> [60.278425] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1144, found 0)
<7> [60.278590] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [60.278807] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2080, found 0)
<7> [60.279040] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [60.279277] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2080, found 0)
<7> [60.279509] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1968, found 0)
<7> [60.279760] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2000, found 0)
<7> [60.279968] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [60.280138] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [60.280306] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [60.280459] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [60.280623] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1144, found 0)
<7> [60.280812] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1144, found 0)
<7> [60.280994] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [60.281165] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [60.281328] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [60.281481] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [60.281655] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [60.281852] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [60.282021] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 285500, found 0)
<7> [60.282177] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 285500, found 0)
<7> [60.282357] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in port_clock (expected 285500, found 0)
<7> [60.282536] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [60.282697] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in avi infoframe
<7> [60.282869] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [60.283039] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [60.283043] i915 0000:00:02.0: colorspace: RGB
<7> [60.283046] i915 0000:00:02.0: scan mode: Underscan
<7> [60.283048] i915 0000:00:02.0: colorimetry: No Data
<7> [60.283051] i915 0000:00:02.0: picture aspect: No Data
<7> [60.283054] i915 0000:00:02.0: active aspect: Same as Picture
<7> [60.283056] i915 0000:00:02.0: itc: No Data
<7> [60.283059] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [60.283061] i915 0000:00:02.0: quantization range: Full
<7> [60.283064] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [60.283067] i915 0000:00:02.0: video code: 0
<7> [60.283070] i915 0000:00:02.0: ycc quantization range: Full
<7> [60.283073] i915 0000:00:02.0: hdmi content type: Graphics
<7> [60.283075] i915 0000:00:02.0: pixel repeat: 0
<7> [60.283078] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [60.283081] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [60.283259] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in spd infoframe
<7> [60.283419] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [60.283571] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [60.283573] i915 0000:00:02.0: vendor: Intel
<7> [60.283574] i915 0000:00:02.0: product: Integrated gfx
<7> [60.283576] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [60.283578] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [60.283784] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe B] fastset requirement not met in hdmi infoframe
<7> [60.283959] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [60.284120] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [60.284122] i915 0000:00:02.0: empty frame
<7> [60.284123] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [60.284276] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe B] fastset requirement not met, forcing full modeset
<7> [60.284456] i915 0000:00:02.0: [drm:intel_unreference_shared_dpll_crtc [i915]] [CRTC:170:pipe B] releasing DPLL 0
<7> [60.284666] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [60.284863] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:170:pipe B] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [60.285076] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:102:plane 1B] ddb ( 0 - 1993) -> ( 0 - 0), size 1993 -> 0
<7> [60.285271] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:165:cursor B] ddb (1993 - 2048) -> ( 0 - 0), size 55 -> 0
<7> [60.285426] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:102:plane 1B] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [60.285586] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:102:plane 1B] lines 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [60.285749] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:102:plane 1B] blocks 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [60.285920] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:102:plane 1B] min_ddb 19, 143, 143, 143, 143, 196, 284, 284, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [60.286108] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:170:pipe B] data rate 0 num active planes 0
<7> [60.286274] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 0
<7> [60.286473] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] Modeset required for cdclk change
<7> [60.286664] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz
<7> [60.286855] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [60.287023] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:170:pipe B] enable: no [modeset]
<7> [60.287186] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:102:plane 1B] fb: [NOFB], visible: no
<7> [60.287377] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:111:plane 2B] fb: [NOFB], visible: no
<7> [60.287540] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:120:plane 3B] fb: [NOFB], visible: no
<7> [60.287703] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:129:plane 4B] fb: [NOFB], visible: no
<7> [60.287878] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:138:plane 5B] fb: [NOFB], visible: no
<7> [60.288038] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:147:plane 6B] fb: [NOFB], visible: no
<7> [60.288234] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:156:plane 7B] fb: [NOFB], visible: no
<7> [60.288385] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:165:cursor B] fb: [NOFB], visible: no
<7> [60.288769] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:313:HDMI-A-1][ENCODER:312:DDI B/PHY B] Disable audio codec on [CRTC:170:pipe B]
<7> [60.301950] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B
<7> [60.304796] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:313:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [60.305432] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [60.305614] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [60.306183] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [60.306347] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:313:HDMI-A-1] Failed to read TMDS config: -6
<7> [60.306360] i915 0000:00:02.0: [drm:intel_ddi_disable [i915]] [CONNECTOR:313:HDMI-A-1] Failed to reset sink scrambling/TMDS bit clock ratio
<7> [60.306896] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe B
<7> [60.311955] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [60.312148] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disable DPLL 0 (active 0x2, on? 1) for [CRTC:170:pipe B]
<7> [60.312363] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disabling DPLL 0
<7> [60.312534] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 172800 kHz, VCO 345600 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [60.312795] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:312:DDI B/PHY B]
<7> [60.313022] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:322:DDI TC1/PHY TC1]
<7> [60.313177] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:324:DP-MST A]
<7> [60.313340] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:325:DP-MST B]
<7> [60.313498] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:326:DP-MST C]
<7> [60.313686] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:327:DP-MST D]
<7> [60.313854] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:332:DDI TC2/PHY TC2]
<7> [60.314033] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:334:DP-MST A]
<7> [60.314185] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:335:DP-MST B]
<7> [60.314365] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:336:DP-MST C]
<7> [60.314543] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:337:DP-MST D]
<7> [60.314697] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:341:DDI TC3/PHY TC3]
<7> [60.314854] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:343:DP-MST A]
<7> [60.315026] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:344:DP-MST B]
<7> [60.315211] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:345:DP-MST C]
<7> [60.315361] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:346:DP-MST D]
<7> [60.315520] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:350:DDI TC4/PHY TC4]
<7> [60.315677] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:352:DP-MST A]
<7> [60.315863] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:353:DP-MST B]
<7> [60.316030] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:354:DP-MST C]
<7> [60.316188] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:355:DP-MST D]
<7> [60.316347] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:313:HDMI-A-1]
<7> [60.316675] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [60.316890] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [60.317096] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [60.317304] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:170:pipe B]
<7> [60.317709] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:240:pipe C]
<7> [60.317883] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:310:pipe D]
<7> [60.318076] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:240:pipe C]
<7> [60.318125] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:313:HDMI-A-1]
<7> [60.318185] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:313:HDMI-A-1] Limiting display bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [60.318357] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [60.318530] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:240:pipe C] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [60.318698] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:312:DDI B/PHY B] [CRTC:240:pipe C]
<7> [60.318892] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.enable (expected no, found yes)
<7> [60.319068] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.active (expected no, found yes)
<7> [60.319235] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2)
<7> [60.319401] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in lane_count (expected 0, found 4)
<7> [60.319593] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [60.319754] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [60.319917] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [60.320092] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2080)
<7> [60.320269] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [60.320450] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2080)
<7> [60.320612] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1968)
<7> [60.320765] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2000)
<7> [60.320930] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [60.321141] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [60.321306] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [60.321461] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [60.321623] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1144)
<7> [60.321824] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1144)
<7> [60.321977] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [60.322159] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2080)
<7> [60.322323] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [60.322512] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2080)
<7> [60.322671] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1968)
<7> [60.322832] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2000)
<7> [60.322985] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [60.323175] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [60.323349] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [60.323517] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [60.323674] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1144)
<7> [60.323844] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1144)
<7> [60.324057] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [60.324223] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [60.324377] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [60.324541] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [60.324737] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [60.324890] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in dpll_hw_state
<7> [60.325107] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [60.325269] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: cfgcr0: 0x0, cfgcr1: 0x0, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [60.325466] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [60.325618] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: cfgcr0: 0x1801be, cfgcr1: 0x488, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [60.325782] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [60.325944] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 285500)
<7> [60.326152] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 285500)
<7> [60.326306] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in port_clock (expected 0, found 285500)
<7> [60.326470] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [60.326631] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in avi infoframe
<7> [60.326784] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [60.326957] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [60.327158] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [60.327160] i915 0000:00:02.0: colorspace: RGB
<7> [60.327161] i915 0000:00:02.0: scan mode: Underscan
<7> [60.327163] i915 0000:00:02.0: colorimetry: No Data
<7> [60.327165] i915 0000:00:02.0: picture aspect: No Data
<7> [60.327166] i915 0000:00:02.0: active aspect: Same as Picture
<7> [60.327168] i915 0000:00:02.0: itc: No Data
<7> [60.327169] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [60.327171] i915 0000:00:02.0: quantization range: Full
<7> [60.327172] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [60.327174] i915 0000:00:02.0: video code: 0
<7> [60.327183] i915 0000:00:02.0: ycc quantization range: Full
<7> [60.327185] i915 0000:00:02.0: hdmi content type: Graphics
<7> [60.327186] i915 0000:00:02.0: pixel repeat: 0
<7> [60.327188] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [60.327190] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in spd infoframe
<7> [60.327358] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [60.327524] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [60.327718] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [60.327721] i915 0000:00:02.0: vendor: Intel
<7> [60.327723] i915 0000:00:02.0: product: Integrated gfx
<7> [60.327725] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [60.327727] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hdmi infoframe
<7> [60.327881] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [60.328059] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [60.328234] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [60.328236] i915 0000:00:02.0: empty frame
<7> [60.328238] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:240:pipe C] fastset requirement not met, forcing full modeset
<7> [60.328520] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [60.328680] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:240:pipe C] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x4
<7> [60.328890] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:172:plane 1C] ddb ( 0 - 0) -> ( 0 - 1993), size 0 -> 1993
<7> [60.329065] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:235:cursor C] ddb ( 0 - 0) -> (1993 - 2048), size 0 -> 55
<7> [60.329248] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:172:plane 1C] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [60.329404] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:172:plane 1C] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0
<7> [60.329560] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:172:plane 1C] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0
<7> [60.329719] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:172:plane 1C] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 143, 143, 143, 143, 196, 284, 284, 31, 0, 0
<7> [60.329913] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:240:pipe C] data rate 1142000 num active planes 1
<7> [60.330090] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 1142
<7> [60.330258] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [60.330438] i915 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [i915]] [PLANE:172:plane 1C] min cdclk (142750 kHz) > [CRTC:240:pipe C] min cdclk (0 kHz)
<7> [60.330638] i915 0000:00:02.0: [drm:intel_bw_calc_min_cdclk [i915]] new bandwidth min cdclk (22305 kHz) > old min cdclk (0 kHz)
<7> [60.330818] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] Modeset required for cdclk change
<7> [60.330982] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [60.331160] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [60.331362] i915 0000:00:02.0: [drm:intel_find_shared_dpll [i915]] [CRTC:240:pipe C] allocated DPLL 0
<7> [60.331522] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc [i915]] [CRTC:240:pipe C] reserving DPLL 0
<7> [60.331690] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:240:pipe C] enable: yes [modeset]
<7> [60.331855] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [60.332039] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: C, pipe bpp: 24, dithering: 0
<7> [60.332223] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [60.332390] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [60.332552] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [60.332729] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [60.332905] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [60.333066] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x0
<7> [60.333243] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [60.333245] i915 0000:00:02.0: colorspace: RGB
<7> [60.333247] i915 0000:00:02.0: scan mode: Underscan
<7> [60.333248] i915 0000:00:02.0: colorimetry: No Data
<7> [60.333250] i915 0000:00:02.0: picture aspect: No Data
<7> [60.333251] i915 0000:00:02.0: active aspect: Same as Picture
<7> [60.333253] i915 0000:00:02.0: itc: No Data
<7> [60.333254] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [60.333256] i915 0000:00:02.0: quantization range: Full
<7> [60.333257] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [60.333259] i915 0000:00:02.0: video code: 0
<7> [60.333262] i915 0000:00:02.0: ycc quantization range: Full
<7> [60.333264] i915 0000:00:02.0: hdmi content type: Graphics
<7> [60.333267] i915 0000:00:02.0: pixel repeat: 0
<7> [60.333270] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [60.333273] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [60.333276] i915 0000:00:02.0: vendor: Intel
<7> [60.333278] i915 0000:00:02.0: product: Integrated gfx
<7> [60.333281] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [60.333284] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [60.333287] i915 0000:00:02.0: empty frame
<7> [60.333290] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 10 00 0f 00 6d a2 00 7f 00 00 00 00 00 00 00 00
<7> [60.333466] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 0d 82 34 12 66 69 74 48 65 61 64 6c 65 73 73 47
<7> [60.333640] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 53 7f 7f 28 0f 7f 07 17 7f ff 3f 7f ff 47 7f ff
<7> [60.333800] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 5f 7f 82 7f 7f 00 7f 7f 51 7f 7f 30 77 7f 03 00
<7> [60.333951] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [60.334140] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vblank delay: 0, framestart delay: 1, MSA timing delay: 0
<7> [60.334326] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0
<7> [60.334482] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: -1, vmax vblank: -2, vmin vtotal: 1, vmax vtotal: 0
<7> [60.334639] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5
<7> [60.334800] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5
<7> [60.334993] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=285500, hd=1920 hb=1920-2080 hs=1968-2000 ht=2080, vd=1080 vb=1080-1144 vs=1083-1088 vt=1144, flags=0x5
<7> [60.335187] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x40 0x5
<7> [60.335351] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=285500, hd=1920 hb=1920-2080 hs=1968-2000 ht=2080, vd=1080 vb=1080-1144 vs=1083-1088 vt=1144, flags=0x5
<7> [60.335515] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 285500, pipe src: 1920x1080+0+0, pixel rate 285500
<7> [60.335710] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [60.335863] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [60.336029] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [60.336218] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [60.336238] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [60.336459] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1801be, cfgcr1: 0x488, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [60.336641] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [60.336666] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [60.336895] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [60.336908] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [60.337157] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [60.338206] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [60.338399] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [60.338597] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [60.338754] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [60.338912] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [60.339068] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [60.339240] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [60.339395] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [60.339548] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [60.339701] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [60.339854] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:172:plane 1C] fb: [FB:366] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [60.340009] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [60.340174] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [60.340328] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:181:plane 2C] fb: [NOFB], visible: no
<7> [60.340481] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:190:plane 3C] fb: [NOFB], visible: no
<7> [60.340635] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:199:plane 4C] fb: [NOFB], visible: no
<7> [60.340788] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:208:plane 5C] fb: [NOFB], visible: no
<7> [60.340939] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:217:plane 6C] fb: [NOFB], visible: no
<7> [60.341091] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:226:plane 7C] fb: [NOFB], visible: no
<7> [60.341260] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:235:cursor C] fb: [NOFB], visible: no
<7> [60.341882] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [60.342041] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [60.342762] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [60.343960] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [60.344128] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [60.344337] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_4
<7> [60.344522] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 192000 kHz, VCO 384000 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [60.344768] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:312:DDI B/PHY B]
<7> [60.344924] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:322:DDI TC1/PHY TC1]
<7> [60.345078] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:324:DP-MST A]
<7> [60.345246] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:325:DP-MST B]
<7> [60.345398] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:326:DP-MST C]
<7> [60.345551] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:327:DP-MST D]
<7> [60.345703] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:332:DDI TC2/PHY TC2]
<7> [60.345854] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:334:DP-MST A]
<7> [60.346006] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:335:DP-MST B]
<7> [60.346156] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:336:DP-MST C]
<7> [60.346325] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:337:DP-MST D]
<7> [60.346477] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:341:DDI TC3/PHY TC3]
<7> [60.346628] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:343:DP-MST A]
<7> [60.346783] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:344:DP-MST B]
<7> [60.346859] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [60.346936] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:345:DP-MST C]
<7> [60.347092] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:346:DP-MST D]
<7> [60.347265] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:350:DDI TC4/PHY TC4]
<7> [60.347421] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:352:DP-MST A]
<7> [60.347570] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:353:DP-MST B]
<7> [60.347720] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:354:DP-MST C]
<7> [60.347870] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:355:DP-MST D]
<7> [60.348115] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [60.348325] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enable DPLL 0 (active 0x4, on? 0) for [CRTC:240:pipe C]
<7> [60.348489] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling DPLL 0
<7> [60.348712] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [60.348946] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe C
<7> [60.349672] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:313:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [60.350315] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [60.350516] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [60.351138] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [60.351320] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:313:HDMI-A-1] Failed to read TMDS config: -6
<7> [60.351333] i915 0000:00:02.0: [drm:intel_ddi_enable [i915]] [CONNECTOR:313:HDMI-A-1] Failed to configure sink scrambling/TMDS bit clock ratio
<7> [60.368393] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:313:HDMI-A-1][ENCODER:312:DDI B/PHY B] Enable audio codec on [CRTC:240:pipe C], 64 bytes ELD
<7> [60.376725] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] HDMI audio pixel clock setting for 285500 not found, falling back to defaults
<7> [60.376889] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)
<7> [60.377032] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<6> [60.377179] snd_hda_codec_hdmi hdaudioC0D2: HDMI: audio coding xtype 5 not expected
<6> [60.377181] snd_hda_codec_hdmi hdaudioC0D2: HDMI: audio coding xtype 0 not expected
<6> [60.377182] snd_hda_codec_hdmi hdaudioC0D2: HDMI: audio coding xtype 10 not expected
<6> [60.377184] snd_hda_codec_hdmi hdaudioC0D2: HDMI: audio coding xtype 6 not expected
<7> [60.377310] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:313:HDMI-A-1]
<7> [60.377548] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:240:pipe C]
<7> [60.429409] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<6> [71.283043] [IGT] kms_flip: finished subtest C-HDMI-A1, FAIL
Created at 2025-03-09 10:19:52