Results for igt@kms_flip@flip-vs-expired-vblank-interruptible@d-hdmi-a1

Result: Fail

integration-manifest git-log-oneline i915_display_info3 igt_runner3 runtimes3 results3.json results3-i915-load.json guc_logs3.tar i915_display_info_post_exec3 boot3 dmesg3

DetailValue
Duration 1.10 seconds
Hostname
shard-tglu-10
Igt-Version
IGT-Version: 2.4-ga5c5d6558 (x86_64) (Linux: 7.1.0-rc1-CI_DRM_18369-gb6f6b69b2dff+ x86_64)
Out
Starting dynamic subtest: D-HDMI-A1
  1920x1080: 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5 
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
  #2 ../tests/kms_flip.c:1920 run_test()
  #3 ../tests/kms_flip.c:2220 __igt_unique____real_main2105()
  #4 ../tests/kms_flip.c:2105 main()
  #5 ../sysdeps/nptl/libc_start_call_main.h:74 __libc_start_call_main()
  #6 ../csu/libc-start.c:128 __libc_start_main@@GLIBC_2.34()
  #7 [_start+0x25]
Dynamic subtest D-HDMI-A1: FAIL (1.098s)
Subtest flip-vs-expired-vblank-interruptible: FAIL (24.081s)
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../lib/igt_kms.c:2352 kmstest_set_connector_dpms()
  #2 ../lib/igt_core.c:2961 igt_atexit_handler()
  #3 ./stdlib/exit.c:109 __run_exit_handlers()
  #4 [exit+0x1e]
  #5 ../lib/igt_core.c:2416 igt_exit()
  #6 [main+0x3f]
  #7 ../sysdeps/nptl/libc_start_call_main.h:74 __libc_start_call_main()
  #8 ../csu/libc-start.c:128 __libc_start_main@@GLIBC_2.34()
  #9 [_start+0x25]
Err
Starting dynamic subtest: D-HDMI-A1
(kms_flip:2096) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
(kms_flip:2096) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2096) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest D-HDMI-A1 failed.
**** DEBUG ****
(kms_flip:2096) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x100000000000001, size=0)
(kms_flip:2096) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:2096) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:2096) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=1, pitch=7680)
(kms_flip:2096) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:2096) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x100000000000001, size=0)
(kms_flip:2096) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:2096) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:2096) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=7680)
(kms_flip:2096) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:2096) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2096) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2096) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS
(kms_flip:2096) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2096) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2096) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS
(kms_flip:2096) igt_kms-INFO:   1920x1080: 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5 
(kms_flip:2096) DEBUG: No stale events found
(kms_flip:2096) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:2096) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:2096) DEBUG: name = flip
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 90.328758
current_received_ts = 90.328331
current_seq = 5
count = 0
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 19us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.328758
last_received_ts = 90.328331
last_seq = 5
current_ts = 90.337090
current_received_ts = 90.336678
current_seq = 6
count = 1
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 17us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.337090
last_received_ts = 90.336678
last_seq = 6
current_ts = 90.345428
current_received_ts = 90.345009
current_seq = 7
count = 2
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 13us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.345428
last_received_ts = 90.345009
last_seq = 7
current_ts = 90.353767
current_received_ts = 90.353340
current_seq = 8
count = 3
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 13us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.353767
last_received_ts = 90.353340
last_seq = 8
current_ts = 90.362099
current_received_ts = 90.361702
current_seq = 9
count = 4
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 12us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.362099
last_received_ts = 90.361702
last_seq = 9
current_ts = 90.370438
current_received_ts = 90.370049
current_seq = 10
count = 5
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 18us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.370438
last_received_ts = 90.370049
last_seq = 10
current_ts = 90.378769
current_received_ts = 90.378357
current_seq = 11
count = 6
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 14us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.378769
last_received_ts = 90.378357
last_seq = 11
current_ts = 90.387115
current_received_ts = 90.386871
current_seq = 12
count = 7
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 23us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.387115
last_received_ts = 90.386871
last_seq = 12
current_ts = 90.395447
current_received_ts = 90.395088
current_seq = 13
count = 8
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 22us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.395447
last_received_ts = 90.395088
last_seq = 13
current_ts = 90.403786
current_received_ts = 90.403458
current_seq = 14
count = 9
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 24us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.403786
last_received_ts = 90.403458
last_seq = 14
current_ts = 90.412117
current_received_ts = 90.411781
current_seq = 15
count = 10
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 26us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.412117
last_received_ts = 90.411781
last_seq = 15
current_ts = 90.420456
current_received_ts = 90.420090
current_seq = 16
count = 11
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 22us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.420456
last_received_ts = 90.420090
last_seq = 16
current_ts = 90.428787
current_received_ts = 90.428413
current_seq = 17
count = 12
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 32us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.428787
last_received_ts = 90.428413
last_seq = 17
current_ts = 90.437126
current_received_ts = 90.436760
current_seq = 18
count = 13
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 22us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.437126
last_received_ts = 90.436760
last_seq = 18
current_ts = 90.445457
current_received_ts = 90.445091
current_seq = 19
count = 14
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 21us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.445457
last_received_ts = 90.445091
last_seq = 19
current_ts = 90.453796
current_received_ts = 90.453499
current_seq = 20
count = 15
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 21us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.453796
last_received_ts = 90.453499
last_seq = 20
current_ts = 90.462135
current_received_ts = 90.461777
current_seq = 21
count = 16
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 40us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.462135
last_received_ts = 90.461777
last_seq = 21
current_ts = 90.470467
current_received_ts = 90.470123
current_seq = 22
count = 17
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 20us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.470467
last_received_ts = 90.470123
last_seq = 22
current_ts = 90.478798
current_received_ts = 90.478516
current_seq = 23
count = 18
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 21us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.478798
last_received_ts = 90.478516
last_seq = 23
current_ts = 90.487137
current_received_ts = 90.486801
current_seq = 24
count = 19
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 32us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.487137
last_received_ts = 90.486801
last_seq = 24
current_ts = 90.495468
current_received_ts = 90.495201
current_seq = 25
count = 20
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 22us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.495468
last_received_ts = 90.495201
last_seq = 25
current_ts = 90.503807
current_received_ts = 90.503464
current_seq = 26
count = 21
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 33us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.503807
last_received_ts = 90.503464
last_seq = 26
current_ts = 90.512138
current_received_ts = 90.511749
current_seq = 27
count = 22
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 24us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.512138
last_received_ts = 90.511749
last_seq = 27
current_ts = 90.520477
current_received_ts = 90.520126
current_seq = 28
count = 23
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 23us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.520477
last_received_ts = 90.520126
last_seq = 28
current_ts = 90.528816
current_received_ts = 90.528481
current_seq = 29
count = 24
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 25us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.528816
last_received_ts = 90.528481
last_seq = 29
current_ts = 90.537155
current_received_ts = 90.536804
current_seq = 30
count = 25
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 24us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.537155
last_received_ts = 90.536804
last_seq = 30
current_ts = 90.545486
current_received_ts = 90.545204
current_seq = 31
count = 26
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 25us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.545486
last_received_ts = 90.545204
last_seq = 31
current_ts = 90.553825
current_received_ts = 90.553467
current_seq = 32
count = 27
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 22us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.553825
last_received_ts = 90.553467
last_seq = 32
current_ts = 90.562157
current_received_ts = 90.561966
current_seq = 33
count = 28
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 40us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.562157
last_received_ts = 90.561966
last_seq = 33
current_ts = 90.570496
current_received_ts = 90.570213
current_seq = 34
count = 29
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 25us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.570496
last_received_ts = 90.570213
last_seq = 34
current_ts = 90.578827
current_received_ts = 90.578606
current_seq = 35
count = 30
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 25us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.578827
last_received_ts = 90.578606
last_seq = 35
current_ts = 90.587166
current_received_ts = 90.586800
current_seq = 36
count = 31
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 21us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.587166
last_received_ts = 90.586800
last_seq = 36
current_ts = 90.595497
current_received_ts = 90.595207
current_seq = 37
count = 32
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 23us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.595497
last_received_ts = 90.595207
last_seq = 37
current_ts = 90.603844
current_received_ts = 90.603439
current_seq = 38
count = 33
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 24us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.603844
last_received_ts = 90.603439
last_seq = 38
current_ts = 90.612167
current_received_ts = 90.611801
current_seq = 39
count = 34
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 30us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.612167
last_received_ts = 90.611801
last_seq = 39
current_ts = 90.620506
current_received_ts = 90.620209
current_seq = 40
count = 35
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 25us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.620506
last_received_ts = 90.620209
last_seq = 40
current_ts = 90.628845
current_received_ts = 90.628502
current_seq = 41
count = 36
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 21us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.628845
last_received_ts = 90.628502
last_seq = 41
current_ts = 90.637177
current_received_ts = 90.636833
current_seq = 42
count = 37
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 21us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.637177
last_received_ts = 90.636833
last_seq = 42
current_ts = 90.645515
current_received_ts = 90.645195
current_seq = 43
count = 38
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 30us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.645515
last_received_ts = 90.645195
last_seq = 43
current_ts = 90.653854
current_received_ts = 90.653549
current_seq = 44
count = 39
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 24us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.653854
last_received_ts = 90.653549
last_seq = 44
current_ts = 90.662186
current_received_ts = 90.661842
current_seq = 45
count = 40
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 32us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.662186
last_received_ts = 90.661842
last_seq = 45
current_ts = 90.670525
current_received_ts = 90.670235
current_seq = 46
count = 41
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 32us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.670525
last_received_ts = 90.670235
last_seq = 46
current_ts = 90.678856
current_received_ts = 90.678520
current_seq = 47
count = 42
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 32us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.678856
last_received_ts = 90.678520
last_seq = 47
current_ts = 90.687195
current_received_ts = 90.686844
current_seq = 48
count = 43
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 24us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.687195
last_received_ts = 90.686844
last_seq = 48
current_ts = 90.695526
current_received_ts = 90.695221
current_seq = 49
count = 44
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 30us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.695526
last_received_ts = 90.695221
last_seq = 49
current_ts = 90.703865
current_received_ts = 90.703514
current_seq = 50
count = 45
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 22us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.703865
last_received_ts = 90.703514
last_seq = 50
current_ts = 90.712204
current_received_ts = 90.711845
current_seq = 51
count = 46
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 22us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.712204
last_received_ts = 90.711845
last_seq = 51
current_ts = 90.720535
current_received_ts = 90.720222
current_seq = 52
count = 47
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 24us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.720535
last_received_ts = 90.720222
last_seq = 52
current_ts = 90.728874
current_received_ts = 90.728531
current_seq = 53
count = 48
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 26us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.728874
last_received_ts = 90.728531
last_seq = 53
current_ts = 90.737213
current_received_ts = 90.736992
current_seq = 54
count = 49
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 26us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.737213
last_received_ts = 90.736992
last_seq = 54
current_ts = 90.745544
current_received_ts = 90.745209
current_seq = 55
count = 50
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 22us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.745544
last_received_ts = 90.745209
last_seq = 55
current_ts = 90.753876
current_received_ts = 90.753616
current_seq = 56
count = 51
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 23us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.753876
last_received_ts = 90.753616
last_seq = 56
current_ts = 90.762215
current_received_ts = 90.761864
current_seq = 57
count = 52
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 23us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.762215
last_received_ts = 90.761864
last_seq = 57
current_ts = 90.770546
current_received_ts = 90.770203
current_seq = 58
count = 53
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 23us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.770546
last_received_ts = 90.770203
last_seq = 58
current_ts = 90.778885
current_received_ts = 90.778534
current_seq = 59
count = 54
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 20us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.778885
last_received_ts = 90.778534
last_seq = 59
current_ts = 90.787224
current_received_ts = 90.786858
current_seq = 60
count = 55
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 21us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.787224
last_received_ts = 90.786858
last_seq = 60
current_ts = 90.795563
current_received_ts = 90.795334
current_seq = 61
count = 56
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 25us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.795563
last_received_ts = 90.795334
last_seq = 61
current_ts = 90.803894
current_received_ts = 90.803543
current_seq = 62
count = 57
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 32us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.803894
last_received_ts = 90.803543
last_seq = 62
current_ts = 90.812233
current_received_ts = 90.811867
current_seq = 63
count = 58
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 22us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.812233
last_received_ts = 90.811867
last_seq = 63
current_ts = 90.820564
current_received_ts = 90.820213
current_seq = 64
count = 59
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 23us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.820564
last_received_ts = 90.820213
last_seq = 64
current_ts = 90.828903
current_received_ts = 90.828552
current_seq = 65
count = 60
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 21us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.828903
last_received_ts = 90.828552
last_seq = 65
current_ts = 90.837234
current_received_ts = 90.836891
current_seq = 66
count = 61
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 30us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.837234
last_received_ts = 90.836891
last_seq = 66
current_ts = 90.845573
current_received_ts = 90.845222
current_seq = 67
count = 62
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 24us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.845573
last_received_ts = 90.845222
last_seq = 67
current_ts = 90.853905
current_received_ts = 90.853577
current_seq = 68
count = 63
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 26us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.853905
last_received_ts = 90.853577
last_seq = 68
current_ts = 90.862244
current_received_ts = 90.861916
current_seq = 69
count = 64
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 26us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.862244
last_received_ts = 90.861916
last_seq = 69
current_ts = 90.870575
current_received_ts = 90.870300
current_seq = 70
count = 65
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 31us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.870575
last_received_ts = 90.870300
last_seq = 70
current_ts = 90.878914
current_received_ts = 90.878571
current_seq = 71
count = 66
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 31us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.878914
last_received_ts = 90.878571
last_seq = 71
current_ts = 90.887253
current_received_ts = 90.886925
current_seq = 72
count = 67
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 29us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.887253
last_received_ts = 90.886925
last_seq = 72
current_ts = 90.895592
current_received_ts = 90.895264
current_seq = 73
count = 68
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 24us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.895592
last_received_ts = 90.895264
last_seq = 73
current_ts = 90.903923
current_received_ts = 90.903603
current_seq = 74
count = 69
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 27us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.903923
last_received_ts = 90.903603
last_seq = 74
current_ts = 90.912254
current_received_ts = 90.911926
current_seq = 75
count = 70
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 28us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.912254
last_received_ts = 90.911926
last_seq = 75
current_ts = 90.920593
current_received_ts = 90.920311
current_seq = 76
count = 71
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 23us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.920593
last_received_ts = 90.920311
last_seq = 76
current_ts = 90.928932
current_received_ts = 90.928535
current_seq = 77
count = 72
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 23us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.928932
last_received_ts = 90.928535
last_seq = 77
current_ts = 90.937263
current_received_ts = 90.937134
current_seq = 78
count = 73
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 26us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.937263
last_received_ts = 90.937134
last_seq = 78
current_ts = 90.945595
current_received_ts = 90.945213
current_seq = 79
count = 74
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 26us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.945595
last_received_ts = 90.945213
last_seq = 79
current_ts = 90.953941
current_received_ts = 90.953590
current_seq = 80
count = 75
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 23us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.953941
last_received_ts = 90.953590
last_seq = 80
current_ts = 90.962265
current_received_ts = 90.961899
current_seq = 81
count = 76
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 31us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.962265
last_received_ts = 90.961899
last_seq = 81
current_ts = 90.970612
current_received_ts = 90.970215
current_seq = 82
count = 77
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 25us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.970612
last_received_ts = 90.970215
last_seq = 82
current_ts = 90.978943
current_received_ts = 90.978706
current_seq = 83
count = 78
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 26us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.978943
last_received_ts = 90.978706
last_seq = 83
current_ts = 90.987274
current_received_ts = 90.986916
current_seq = 84
count = 79
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 26us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.987274
last_received_ts = 90.986916
last_seq = 84
current_ts = 90.995613
current_received_ts = 90.995384
current_seq = 85
count = 80
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 25us
(kms_flip:2096) DEBUG: name = flip
last_ts = 90.995613
last_received_ts = 90.995384
last_seq = 85
current_ts = 91.003944
current_received_ts = 91.003563
current_seq = 86
count = 81
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 31us
(kms_flip:2096) DEBUG: name = flip
last_ts = 91.003944
last_received_ts = 91.003563
last_seq = 86
current_ts = 91.012291
current_received_ts = 91.011909
current_seq = 87
count = 82
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 29us
(kms_flip:2096) DEBUG: name = flip
last_ts = 91.012291
last_received_ts = 91.011909
last_seq = 87
current_ts = 91.020622
current_received_ts = 91.020287
current_seq = 88
count = 83
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 26us
(kms_flip:2096) DEBUG: name = flip
last_ts = 91.020622
last_received_ts = 91.020287
last_seq = 88
current_ts = 91.028954
current_received_ts = 91.028610
current_seq = 89
count = 84
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 32us
(kms_flip:2096) DEBUG: name = flip
last_ts = 91.028954
last_received_ts = 91.028610
last_seq = 89
current_ts = 91.037292
current_received_ts = 91.036934
current_seq = 90
count = 85
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 21us
(kms_flip:2096) DEBUG: name = flip
last_ts = 91.037292
last_received_ts = 91.036934
last_seq = 90
current_ts = 91.045624
current_received_ts = 91.045349
current_seq = 91
count = 86
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 22us
(kms_flip:2096) DEBUG: name = flip
last_ts = 91.045624
last_received_ts = 91.045349
last_seq = 91
current_ts = 91.053963
current_received_ts = 91.053619
current_seq = 92
count = 87
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 26us
(kms_flip:2096) DEBUG: name = flip
last_ts = 91.053963
last_received_ts = 91.053619
last_seq = 92
current_ts = 91.062302
current_received_ts = 91.062088
current_seq = 93
count = 88
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 25us
(kms_flip:2096) DEBUG: name = flip
last_ts = 91.062302
last_received_ts = 91.062088
last_seq = 93
current_ts = 91.070633
current_received_ts = 91.070305
current_seq = 94
count = 89
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 26us
(kms_flip:2096) DEBUG: name = flip
last_ts = 91.070633
last_received_ts = 91.070305
last_seq = 94
current_ts = 91.078972
current_received_ts = 91.078621
current_seq = 95
count = 90
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 24us
(kms_flip:2096) DEBUG: name = flip
last_ts = 91.078972
last_received_ts = 91.078621
last_seq = 95
current_ts = 91.087311
current_received_ts = 91.086967
current_seq = 96
count = 91
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 39us
(kms_flip:2096) DEBUG: name = flip
last_ts = 91.087311
last_received_ts = 91.086967
last_seq = 96
current_ts = 91.095642
current_received_ts = 91.095261
current_seq = 97
count = 92
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 28us
(kms_flip:2096) DEBUG: name = flip
last_ts = 91.095642
last_received_ts = 91.095261
last_seq = 97
current_ts = 91.103973
current_received_ts = 91.103737
current_seq = 98
count = 93
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 26us
(kms_flip:2096) DEBUG: name = flip
last_ts = 91.103973
last_received_ts = 91.103737
last_seq = 98
current_ts = 91.112312
current_received_ts = 91.111931
current_seq = 99
count = 94
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 29us
(kms_flip:2096) DEBUG: name = flip
last_ts = 91.112312
last_received_ts = 91.111931
last_seq = 99
current_ts = 91.120651
current_received_ts = 91.120300
current_seq = 100
count = 95
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 25us
(kms_flip:2096) DEBUG: name = flip
last_ts = 91.120651
last_received_ts = 91.120300
last_seq = 100
current_ts = 91.128983
current_received_ts = 91.128647
current_seq = 101
count = 96
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 25us
(kms_flip:2096) DEBUG: name = flip
last_ts = 91.128983
last_received_ts = 91.128647
last_seq = 101
current_ts = 91.137321
current_received_ts = 91.136955
current_seq = 102
count = 97
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 24us
(kms_flip:2096) DEBUG: name = flip
last_ts = 91.137321
last_received_ts = 91.136955
last_seq = 102
current_ts = 91.145653
current_received_ts = 91.145409
current_seq = 103
count = 98
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 25us
(kms_flip:2096) DEBUG: name = flip
last_ts = 91.145653
last_received_ts = 91.145409
last_seq = 103
current_ts = 91.153984
current_received_ts = 91.153625
current_seq = 104
count = 99
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 21us
(kms_flip:2096) DEBUG: name = flip
last_ts = 91.153984
last_received_ts = 91.153625
last_seq = 104
current_ts = 91.162323
current_received_ts = 91.161957
current_seq = 105
count = 100
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 44us
(kms_flip:2096) DEBUG: name = flip
last_ts = 91.162323
last_received_ts = 91.161957
last_seq = 105
current_ts = 91.170662
current_received_ts = 91.170349
current_seq = 106
count = 101
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 27us
(kms_flip:2096) DEBUG: name = flip
last_ts = 91.170662
last_received_ts = 91.170349
last_seq = 106
current_ts = 91.178993
current_received_ts = 91.178658
current_seq = 107
count = 102
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 31us
(kms_flip:2096) DEBUG: name = flip
last_ts = 91.178993
last_received_ts = 91.178658
last_seq = 107
current_ts = 91.187332
current_received_ts = 91.186981
current_seq = 108
count = 103
seq_step = 1
(kms_flip:2096) DEBUG: Vblank took 526us
(kms_flip:2096) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
(kms_flip:2096) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2096) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:2096) igt_core-INFO: Stack trace:
(kms_flip:2096) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2096) igt_core-INFO:   #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
(kms_flip:2096) igt_core-INFO:   #2 ../tests/kms_flip.c:1920 run_test()
(kms_flip:2096) igt_core-INFO:   #3 ../tests/kms_flip.c:2220 __igt_unique____real_main2105()
(kms_flip:2096) igt_core-INFO:   #4 ../tests/kms_flip.c:2105 main()
(kms_flip:2096) igt_core-INFO:   #5 ../sysdeps/nptl/libc_start_call_main.h:74 __libc_start_call_main()
(kms_flip:2096) igt_core-INFO:   #6 ../csu/libc-start.c:128 __libc_start_main@@GLIBC_2.34()
(kms_flip:2096) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest D-HDMI-A1: FAIL (1.098s)
Subtest flip-vs-expired-vblank-interruptible: FAIL (24.081s)
(kms_flip:2096) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2352:
(kms_flip:2096) igt_kms-CRITICAL: Failed assertion: found_it
(kms_flip:2096) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor
(kms_flip:2096) igt_kms-CRITICAL: DPMS property not found on 588
Dmesg
<6> [90.184924] [IGT] kms_flip: starting dynamic subtest D-HDMI-A1
<7> [90.185771] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:637]
<7> [90.186013] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:638]
<7> [90.206224] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:171:pipe A]
<7> [90.206398] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:309:pipe B]
<7> [90.206510] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:447:pipe C]
<7> [90.206590] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.enable (expected yes, found no)
<7> [90.206792] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.active (expected yes, found no)
<7> [90.206958] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in cpu_transcoder (expected 2, found -1)
<7> [90.207228] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in lane_count (expected 4, found 0)
<7> [90.207439] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [90.207606] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [90.207768] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [90.207929] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2080, found 0)
<7> [90.208100] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [90.208299] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2080, found 0)
<7> [90.208457] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1968, found 0)
<7> [90.208617] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2000, found 0)
<7> [90.208777] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [90.208938] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [90.209106] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [90.209413] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [90.209574] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1144, found 0)
<7> [90.209733] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1144, found 0)
<7> [90.209892] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [90.210052] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2080, found 0)
<7> [90.210229] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [90.210415] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2080, found 0)
<7> [90.210593] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1968, found 0)
<7> [90.210753] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2000, found 0)
<7> [90.210914] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [90.211073] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [90.211257] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [90.211445] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [90.211604] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1144, found 0)
<7> [90.211763] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1144, found 0)
<7> [90.211922] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [90.212091] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [90.212283] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [90.212447] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [90.212609] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [90.212771] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [90.212932] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 285500, found 0)
<7> [90.213102] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 285500, found 0)
<7> [90.213289] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in port_clock (expected 285500, found 0)
<7> [90.213447] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [90.213608] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in avi infoframe
<7> [90.213766] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [90.213924] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [90.213926] i915 0000:00:02.0: colorspace: RGB
<7> [90.213927] i915 0000:00:02.0: scan mode: Underscan
<7> [90.213929] i915 0000:00:02.0: colorimetry: No Data
<7> [90.213930] i915 0000:00:02.0: picture aspect: No Data
<7> [90.213932] i915 0000:00:02.0: active aspect: Same as Picture
<7> [90.213933] i915 0000:00:02.0: itc: No Data
<7> [90.213935] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [90.213936] i915 0000:00:02.0: quantization range: Full
<7> [90.213937] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [90.213939] i915 0000:00:02.0: video code: 0
<7> [90.213940] i915 0000:00:02.0: ycc quantization range: Full
<7> [90.213942] i915 0000:00:02.0: hdmi content type: Graphics
<7> [90.213943] i915 0000:00:02.0: pixel repeat: 0
<7> [90.213944] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [90.213946] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [90.214113] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in spd infoframe
<7> [90.214304] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [90.214464] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [90.214465] i915 0000:00:02.0: vendor: Intel
<7> [90.214467] i915 0000:00:02.0: product: Integrated gfx
<7> [90.214468] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [90.214470] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [90.214628] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hdmi infoframe
<7> [90.214786] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [90.214944] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [90.214946] i915 0000:00:02.0: empty frame
<7> [90.214947] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [90.215117] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:447:pipe C] fastset requirement not met, forcing full modeset
<7> [90.215306] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:447:pipe C] releasing DPLL 0
<7> [90.215497] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [90.215657] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:447:pipe C] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x4 -> 0x0
<7> [90.215848] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:441:cursor C] ddb (1993 - 2048) -> ( 0 - 0), size 55 -> 0
<7> [90.216025] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:447:pipe C] min cdclk: 192000 kHz -> 0 kHz
<7> [90.216288] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [90.216455] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz
<7> [90.216619] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [90.216782] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:447:pipe C] enable: no [modeset]
<7> [90.216945] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:311:plane 1C] fb: [NOFB], visible: no
<7> [90.217119] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:341:plane 2C] fb: [NOFB], visible: no
<7> [90.217305] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:371:plane 3C] fb: [NOFB], visible: no
<7> [90.217464] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:401:plane 4C] fb: [NOFB], visible: no
<7> [90.217622] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:411:plane 5C] fb: [NOFB], visible: no
<7> [90.217781] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:421:plane 6C] fb: [NOFB], visible: no
<7> [90.217940] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:431:plane 7C] fb: [NOFB], visible: no
<7> [90.218109] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:441:cursor C] fb: [NOFB], visible: no
<7> [90.218372] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:588:HDMI-A-1][ENCODER:587:DDI B/PHY B] Disable audio codec on [CRTC:447:pipe C]
<7> [90.233157] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B
<7> [90.235099] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:588:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [90.235646] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [90.235805] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [90.236359] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [90.236532] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:588:HDMI-A-1] Failed to read TMDS config: -6
<7> [90.236549] i915 0000:00:02.0: [drm:intel_ddi_disable [i915]] [CONNECTOR:588:HDMI-A-1] Failed to reset sink scrambling/TMDS bit clock ratio
<7> [90.236731] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe C
<7> [90.243157] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [90.243455] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x4, on? 1) for [CRTC:447:pipe C]
<7> [90.243731] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [90.244038] 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> [90.244527] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI B/PHY B]
<7> [90.244948] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:597:DDI TC1/PHY TC1]
<7> [90.245284] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST A]
<7> [90.245593] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST B]
<7> [90.245886] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST C]
<7> [90.246243] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:602:DP-MST D]
<7> [90.246581] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:607:DDI TC2/PHY TC2]
<7> [90.246881] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST A]
<7> [90.247221] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST B]
<7> [90.247540] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST C]
<7> [90.247804] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST D]
<7> [90.248066] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:616:DDI TC3/PHY TC3]
<7> [90.248367] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST A]
<7> [90.248628] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST B]
<7> [90.248889] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST C]
<7> [90.249172] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:621:DP-MST D]
<7> [90.249435] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DDI TC4/PHY TC4]
<7> [90.249699] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST A]
<7> [90.249961] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST B]
<7> [90.250187] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST C]
<7> [90.250403] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:630:DP-MST D]
<7> [90.250602] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:588:HDMI-A-1]
<7> [90.250992] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [90.251277] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_4
<7> [90.251525] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [90.251750] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [90.251981] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:447:pipe C]
<7> [90.252567] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:585:pipe D]
<7> [90.252785] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:585:pipe D]
<7> [90.252821] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:588:HDMI-A-1]
<7> [90.252903] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:588:HDMI-A-1] Limiting target display pipe bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [90.253124] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [90.253308] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:585:pipe D] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [90.253551] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:587:DDI B/PHY B] [CRTC:585:pipe D]
<7> [90.253728] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.enable (expected no, found yes)
<7> [90.253895] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.active (expected no, found yes)
<7> [90.254058] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in cpu_transcoder (expected -1, found 3)
<7> [90.254245] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in lane_count (expected 0, found 4)
<7> [90.254491] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [90.254655] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [90.254816] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [90.254976] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2080)
<7> [90.255173] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [90.255394] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2080)
<7> [90.255563] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1968)
<7> [90.255725] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2000)
<7> [90.255885] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [90.256103] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [90.256334] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [90.256503] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [90.256669] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1144)
<7> [90.256833] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1144)
<7> [90.256995] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [90.257181] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2080)
<7> [90.257343] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [90.257503] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2080)
<7> [90.257664] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1968)
<7> [90.257823] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2000)
<7> [90.257982] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [90.258158] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [90.258334] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [90.258500] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [90.258659] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1144)
<7> [90.258818] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1144)
<7> [90.258976] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [90.259266] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [90.259448] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [90.259628] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [90.259806] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [90.259984] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in dpll_hw_state
<7> [90.260200] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [90.260378] 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> [90.260559] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [90.260737] 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> [90.260918] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [90.261108] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 285500)
<7> [90.261287] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 285500)
<7> [90.261466] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in port_clock (expected 0, found 285500)
<7> [90.261643] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [90.261804] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in avi infoframe
<7> [90.261962] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [90.262141] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [90.262312] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [90.262314] i915 0000:00:02.0: colorspace: RGB
<7> [90.262316] i915 0000:00:02.0: scan mode: Underscan
<7> [90.262317] i915 0000:00:02.0: colorimetry: No Data
<7> [90.262319] i915 0000:00:02.0: picture aspect: No Data
<7> [90.262320] i915 0000:00:02.0: active aspect: Same as Picture
<7> [90.262321] i915 0000:00:02.0: itc: No Data
<7> [90.262323] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [90.262324] i915 0000:00:02.0: quantization range: Full
<7> [90.262326] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [90.262327] i915 0000:00:02.0: video code: 0
<7> [90.262328] i915 0000:00:02.0: ycc quantization range: Full
<7> [90.262330] i915 0000:00:02.0: hdmi content type: Graphics
<7> [90.262331] i915 0000:00:02.0: pixel repeat: 0
<7> [90.262332] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [90.262334] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in spd infoframe
<7> [90.262493] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [90.262658] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [90.262816] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [90.262818] i915 0000:00:02.0: vendor: Intel
<7> [90.262820] i915 0000:00:02.0: product: Integrated gfx
<7> [90.262821] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [90.262823] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hdmi infoframe
<7> [90.262981] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [90.263171] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [90.263353] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [90.263355] i915 0000:00:02.0: empty frame
<7> [90.263357] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:585:pipe D] fastset requirement not met, forcing full modeset
<7> [90.263556] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [90.263717] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:585:pipe D] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x8
<7> [90.263917] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:449:plane 1D] ddb ( 0 - 0) -> ( 0 - 1993), size 0 -> 1993
<7> [90.264085] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:579:cursor D] ddb ( 0 - 0) -> (1993 - 2048), size 0 -> 55
<7> [90.264258] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [90.264416] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0
<7> [90.264570] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0
<7> [90.264732] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] 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> [90.264904] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:585:pipe D] data rate 1142000 num active planes 1
<7> [90.265078] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 1142
<7> [90.265255] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [90.265437] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:585:pipe D] min cdclk: 0 kHz -> 192000 kHz
<7> [90.265643] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [90.265805] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [90.265964] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [90.266153] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:585:pipe D] allocated DPLL 0
<7> [90.266330] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:585:pipe D] reserving DPLL 0
<7> [90.266496] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:585:pipe D] enable: yes [modeset]
<7> [90.266662] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [90.266833] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: D, pipe bpp: 24, dithering: 0
<7> [90.266994] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [90.267171] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [90.267344] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [90.267503] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [90.267663] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [90.267822] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x0
<7> [90.267980] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [90.267982] i915 0000:00:02.0: colorspace: RGB
<7> [90.267984] i915 0000:00:02.0: scan mode: Underscan
<7> [90.267985] i915 0000:00:02.0: colorimetry: No Data
<7> [90.267987] i915 0000:00:02.0: picture aspect: No Data
<7> [90.267988] i915 0000:00:02.0: active aspect: Same as Picture
<7> [90.267989] i915 0000:00:02.0: itc: No Data
<7> [90.267991] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [90.267992] i915 0000:00:02.0: quantization range: Full
<7> [90.267993] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [90.267995] i915 0000:00:02.0: video code: 0
<7> [90.267996] i915 0000:00:02.0: ycc quantization range: Full
<7> [90.267997] i915 0000:00:02.0: hdmi content type: Graphics
<7> [90.267999] i915 0000:00:02.0: pixel repeat: 0
<7> [90.268000] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [90.268002] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [90.268003] i915 0000:00:02.0: vendor: Intel
<7> [90.268005] i915 0000:00:02.0: product: Integrated gfx
<7> [90.268006] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [90.268008] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [90.268009] i915 0000:00:02.0: empty frame
<7> [90.268011] 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> [90.268184] 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> [90.268368] 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> [90.268525] 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> [90.268682] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [90.268880] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [90.269078] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1144, vmax: 1144, flipline: 1144, pipeline full: 61, guardband: 63 vsync start: 0, vsync end: 0
<7> [90.269259] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1081, vmax vblank: 1081, vmin vtotal: 1144, vmax vtotal: 1144
<7> [90.269417] 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> [90.269587] 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> [90.269746] 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> [90.269947] 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> [90.270127] 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> [90.270150] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [90.270306] 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> [90.270346] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [90.270474] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 285500, pixel rate 285500, min cdclk 192000, min voltage level 0
<7> [90.270519] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [90.270634] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [90.270801] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [90.270971] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [90.271148] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [90.271320] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [90.271478] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [90.271637] 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> [90.271807] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [90.271966] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [90.272140] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [90.272309] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [90.272467] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [90.272624] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [90.272782] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [90.272792] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [90.272942] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [90.273131] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [90.273301] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [90.273459] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [90.273619] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [90.273778] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:449:plane 1D] fb: [FB:637] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [90.273937] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [90.274110] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [90.274283] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:479:plane 2D] fb: [NOFB], visible: no
<7> [90.274440] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:509:plane 3D] fb: [NOFB], visible: no
<7> [90.274598] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:539:plane 4D] fb: [NOFB], visible: no
<7> [90.274757] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:549:plane 5D] fb: [NOFB], visible: no
<7> [90.274915] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:559:plane 6D] fb: [NOFB], visible: no
<7> [90.275086] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:569:plane 7D] fb: [NOFB], visible: no
<7> [90.275285] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:579:cursor D] fb: [NOFB], visible: no
<7> [90.276084] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [90.276325] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [90.277015] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [90.278212] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [90.278386] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [90.278562] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_4
<7> [90.278734] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_5
<7> [90.278929] 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> [90.279206] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI B/PHY B]
<7> [90.279374] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:597:DDI TC1/PHY TC1]
<7> [90.279539] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST A]
<7> [90.279699] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST B]
<7> [90.279858] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST C]
<7> [90.280016] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:602:DP-MST D]
<7> [90.280193] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:607:DDI TC2/PHY TC2]
<7> [90.280362] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST A]
<7> [90.280519] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST B]
<7> [90.280676] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST C]
<7> [90.280834] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST D]
<7> [90.280991] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:616:DDI TC3/PHY TC3]
<7> [90.281170] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST A]
<7> [90.281344] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST B]
<7> [90.281501] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST C]
<7> [90.281659] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:621:DP-MST D]
<7> [90.281815] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DDI TC4/PHY TC4]
<7> [90.281973] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST A]
<7> [90.282146] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST B]
<7> [90.282316] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST C]
<7> [90.282473] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:630:DP-MST D]
<7> [90.282755] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [90.282956] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x8, on? 0) for [CRTC:585:pipe D]
<7> [90.283139] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [90.283381] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [90.283623] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe D
<7> [90.284347] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:588:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [90.284919] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [90.285075] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [90.285600] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [90.285752] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:588:HDMI-A-1] Failed to read TMDS config: -6
<7> [90.285769] i915 0000:00:02.0: [drm:intel_ddi_enable [i915]] [CONNECTOR:588:HDMI-A-1] Failed to configure sink scrambling/TMDS bit clock ratio
<7> [90.294420] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:588:HDMI-A-1][ENCODER:587:DDI B/PHY B] Enable audio codec on [CRTC:585:pipe D], 64 bytes ELD
<7> [90.302747] 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> [90.302924] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)
<7> [90.303403] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<6> [90.303571] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 5 not expected
<6> [90.303574] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 0 not expected
<6> [90.303576] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 10 not expected
<6> [90.303577] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 6 not expected
<7> [90.303709] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:588:HDMI-A-1]
<7> [90.303968] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:585:pipe D]
<7> [90.310988] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [90.315103] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [90.315135] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<6> [91.282694] [IGT] kms_flip: finished subtest D-HDMI-A1, FAIL
<6> [91.284523] [IGT] kms_flip: finished subtest flip-vs-expired-vblank-interruptible, FAIL
<7> [91.288181] i915 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:638] from all active usage due to RMFB ioctl
<7> [91.288457] i915 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:449:plane 1D] because [FB:638] is removed
<7> [91.288507] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:449:plane 1D] ddb ( 0 - 1993) -> ( 0 - 0), size 1993 -> 0
<7> [91.288709] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [91.288870] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] lines 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [91.289052] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] blocks 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [91.289229] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] 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> [91.289435] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:585:pipe D] data rate 0 num active planes 0
<7> [91.289603] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 0
<7> [91.289768] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<6> [91.296048] [IGT] kms_flip: exiting, ret=98
<7> [91.354241] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:588:HDMI-A-1] Limiting target display pipe bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [91.354471] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [91.354644] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [91.354831] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:587:DDI B/PHY B] [CRTC:171:pipe A]
<7> [91.355008] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [91.355258] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [91.355505] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [91.355750] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [91.356012] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [91.356259] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [91.356497] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [91.356742] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2080)
<7> [91.357005] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [91.357247] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2080)
<7> [91.357486] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1968)
<7> [91.357727] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2000)
<7> [91.358152] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [91.358389] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [91.358625] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [91.358860] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [91.359950] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1144)
<7> [91.360160] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1144)
<7> [91.360337] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [91.360504] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2080)
<7> [91.360704] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [91.360872] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2080)
<7> [91.361061] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1968)
<7> [91.361233] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2000)
<7> [91.361433] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [91.361593] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [91.361761] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [91.361947] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [91.362145] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1144)
<7> [91.362303] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1144)
<7> [91.362469] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [91.362635] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [91.362824] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [91.363000] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [91.363166] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [91.363332] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [91.363531] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 285500)
<7> [91.363691] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 285500)
<7> [91.363857] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in port_clock (expected 0, found 285500)
<7> [91.364041] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [91.364235] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in avi infoframe
<7> [91.364394] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [91.364559] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [91.364724] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [91.364727] i915 0000:00:02.0: colorspace: RGB
<7> [91.364729] i915 0000:00:02.0: scan mode: Underscan
<7> [91.364732] i915 0000:00:02.0: colorimetry: No Data
<7> [91.364734] i915 0000:00:02.0: picture aspect: No Data
<7> [91.364737] i915 0000:00:02.0: active aspect: Same as Picture
<7> [91.364740] i915 0000:00:02.0: itc: No Data
<7> [91.364743] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [91.364746] i915 0000:00:02.0: quantization range: Full
<7> [91.364748] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [91.364751] i915 0000:00:02.0: video code: 0
<7> [91.364754] i915 0000:00:02.0: ycc quantization range: Full
<7> [91.364757] i915 0000:00:02.0: hdmi content type: Graphics
<7> [91.364760] i915 0000:00:02.0: pixel repeat: 0
<7> [91.364763] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [91.364766] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in spd infoframe
<7> [91.364969] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [91.365136] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [91.365294] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [91.365296] i915 0000:00:02.0: vendor: Intel
<7> [91.365298] i915 0000:00:02.0: product: Integrated gfx
<7> [91.365300] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [91.365302] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hdmi infoframe
<7> [91.365493] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [91.365665] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [91.365830] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [91.365832] i915 0000:00:02.0: empty frame
<7> [91.365835] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] fastset requirement not met, forcing full modeset
<7> [91.366016] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.enable (expected yes, found no)
<7> [91.366210] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.active (expected yes, found no)
<7> [91.366376] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in cpu_transcoder (expected 3, found -1)
<7> [91.366543] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in lane_count (expected 4, found 0)
<7> [91.366706] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [91.366898] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [91.367096] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [91.367268] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2080, found 0)
<7> [91.367437] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [91.367637] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2080, found 0)
<7> [91.367798] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1968, found 0)
<7> [91.367977] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2000, found 0)
<7> [91.368147] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [91.368345] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [91.368503] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [91.368669] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [91.368836] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1144, found 0)
<7> [91.369038] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1144, found 0)
<7> [91.369202] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [91.369364] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2080, found 0)
<7> [91.369532] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [91.369726] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2080, found 0)
<7> [91.369884] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1968, found 0)
<7> [91.370082] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2000, found 0)
<7> [91.370262] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [91.370449] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [91.370614] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [91.370773] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [91.370960] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1144, found 0)
<7> [91.371138] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1144, found 0)
<7> [91.371305] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [91.371464] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [91.371650] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [91.371830] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [91.372030] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [91.372189] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [91.372368] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 285500, found 0)
<7> [91.372558] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 285500, found 0)
<7> [91.372722] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in port_clock (expected 285500, found 0)
<7> [91.372881] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [91.373083] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in avi infoframe
<7> [91.373265] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [91.373434] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [91.373437] i915 0000:00:02.0: colorspace: RGB
<7> [91.373438] i915 0000:00:02.0: scan mode: Underscan
<7> [91.373440] i915 0000:00:02.0: colorimetry: No Data
<7> [91.373441] i915 0000:00:02.0: picture aspect: No Data
<7> [91.373443] i915 0000:00:02.0: active aspect: Same as Picture
<7> [91.373445] i915 0000:00:02.0: itc: No Data
<7> [91.373446] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [91.373448] i915 0000:00:02.0: quantization range: Full
<7> [91.373449] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [91.373451] i915 0000:00:02.0: video code: 0
<7> [91.373452] i915 0000:00:02.0: ycc quantization range: Full
<7> [91.373454] i915 0000:00:02.0: hdmi content type: Graphics
<7> [91.373456] i915 0000:00:02.0: pixel repeat: 0
<7> [91.373457] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [91.373459] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [91.373623] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in spd infoframe
<7> [91.373818] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [91.373995] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [91.373997] i915 0000:00:02.0: vendor: Intel
<7> [91.373999] i915 0000:00:02.0: product: Integrated gfx
<7> [91.374000] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [91.374002] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [91.374170] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hdmi infoframe
<7> [91.374339] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [91.374537] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [91.374539] i915 0000:00:02.0: empty frame
<7> [91.374541] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [91.374701] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:585:pipe D] fastset requirement not met, forcing full modeset
<7> [91.374868] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:585:pipe D] releasing DPLL 0
<7> [91.375108] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:171:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x8 -> 0x1
<7> [91.375296] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:585:pipe D] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x8 -> 0x1
<7> [91.375480] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 1993), size 0 -> 1993
<7> [91.375648] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:165:cursor A] ddb ( 0 - 0) -> (1993 - 2048), size 0 -> 55
<7> [91.375814] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [91.376014] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0
<7> [91.376175] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0
<7> [91.376340] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 143, 143, 143, 143, 196, 284, 284, 31, 0, 0
<7> [91.376505] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:579:cursor D] ddb (1993 - 2048) -> ( 0 - 0), size 55 -> 0
<7> [91.376718] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:171:pipe A] data rate 1142000 num active planes 1
<7> [91.376889] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 1142
<7> [91.377075] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [91.377291] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:171:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [91.377478] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:585:pipe D] min cdclk: 192000 kHz -> 0 kHz
<7> [91.377674] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:171:pipe A] allocated DPLL 0
<7> [91.377853] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:171:pipe A] reserving DPLL 0
<7> [91.378069] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:171:pipe A] enable: yes [modeset]
<7> [91.378235] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [91.378406] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [91.378575] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [91.378774] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [91.378948] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [91.379114] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [91.379282] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [91.379483] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x0
<7> [91.379642] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [91.379644] i915 0000:00:02.0: colorspace: RGB
<7> [91.379645] i915 0000:00:02.0: scan mode: Underscan
<7> [91.379647] i915 0000:00:02.0: colorimetry: No Data
<7> [91.379649] i915 0000:00:02.0: picture aspect: No Data
<7> [91.379650] i915 0000:00:02.0: active aspect: Same as Picture
<7> [91.379652] i915 0000:00:02.0: itc: No Data
<7> [91.379654] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [91.379657] i915 0000:00:02.0: quantization range: Full
<7> [91.379660] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [91.379663] i915 0000:00:02.0: video code: 0
<7> [91.379666] i915 0000:00:02.0: ycc quantization range: Full
<7> [91.379669] i915 0000:00:02.0: hdmi content type: Graphics
<7> [91.379672] i915 0000:00:02.0: pixel repeat: 0
<7> [91.379675] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [91.379678] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [91.379679] i915 0000:00:02.0: vendor: Intel
<7> [91.379681] i915 0000:00:02.0: product: Integrated gfx
<7> [91.379683] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [91.379684] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [91.379686] i915 0000:00:02.0: empty frame
<7> [91.379688] 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> [91.379849] 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> [91.380046] 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> [91.380241] 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> [91.380408] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [91.380568] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [91.380745] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1144, vmax: 1144, flipline: 1144, pipeline full: 61, guardband: 63 vsync start: 0, vsync end: 0
<7> [91.380944] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1081, vmax vblank: 1081, vmin vtotal: 1144, vmax vtotal: 1144
<7> [91.381108] 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> [91.381268] 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> [91.381443] 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> [91.381632] 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> [91.381799] 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> [91.381970] 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> [91.382148] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 285500, pixel rate 285500, min cdclk 192000, min voltage level 0
<7> [91.382336] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [91.382502] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [91.382661] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [91.382838] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [91.383035] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [91.383205] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [91.383367] 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> [91.383565] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [91.383732] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [91.383898] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [91.384084] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [91.384275] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [91.384441] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [91.384607] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [91.384772] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [91.384972] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [91.385130] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [91.385296] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [91.385461] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [91.385658] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [FB:635] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [91.385819] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [91.385999] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [91.386171] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [91.386374] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [91.386536] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [91.386704] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [91.386872] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:plane 6A] fb: [NOFB], visible: no
<7> [91.387091] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:155:plane 7A] fb: [NOFB], visible: no
<7> [91.387251] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:165:cursor A] fb: [NOFB], visible: no
<7> [91.387417] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:585:pipe D] enable: no [modeset]
<7> [91.387583] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:449:plane 1D] fb: [NOFB], visible: no
<7> [91.387783] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:479:plane 2D] fb: [NOFB], visible: no
<7> [91.387951] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:509:plane 3D] fb: [NOFB], visible: no
<7> [91.388117] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:539:plane 4D] fb: [NOFB], visible: no
<7> [91.388285] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:549:plane 5D] fb: [NOFB], visible: no
<7> [91.388487] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:559:plane 6D] fb: [NOFB], visible: no
<7> [91.388645] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:569:plane 7D] fb: [NOFB], visible: no
<7> [91.388810] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:579:cursor D] fb: [NOFB], visible: no
<7> [91.389230] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:588:HDMI-A-1][ENCODER:587:DDI B/PHY B] Disable audio codec on [CRTC:585:pipe D]
<7> [91.402883] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B
<7> [91.406948] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:588:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [91.407604] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [91.407773] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [91.408364] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [91.408619] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:588:HDMI-A-1] Failed to read TMDS config: -6
<7> [91.408647] i915 0000:00:02.0: [drm:intel_ddi_disable [i915]] [CONNECTOR:588:HDMI-A-1] Failed to reset sink scrambling/TMDS bit clock ratio
<7> [91.409201] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe D
<7> [91.411955] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [91.412261] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x8, on? 1) for [CRTC:585:pipe D]
<7> [91.412507] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [91.412691] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI B/PHY B]
<7> [91.412858] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:597:DDI TC1/PHY TC1]
<7> [91.413036] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST A]
<7> [91.413199] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST B]
<7> [91.413361] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST C]
<7> [91.413523] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:602:DP-MST D]
<7> [91.413684] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:607:DDI TC2/PHY TC2]
<7> [91.413846] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST A]
<7> [91.414026] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST B]
<7> [91.414192] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST C]
<7> [91.414355] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST D]
<7> [91.414516] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:616:DDI TC3/PHY TC3]
<7> [91.414675] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST A]
<7> [91.414834] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST B]
<7> [91.415020] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST C]
<7> [91.415184] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:621:DP-MST D]
<7> [91.415360] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DDI TC4/PHY TC4]
<7> [91.415523] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST A]
<7> [91.415684] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST B]
<7> [91.415846] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST C]
<7> [91.416022] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:630:DP-MST D]
<7> [91.416435] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:171:pipe A]
<7> [91.416602] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [91.416978] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [91.417271] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [91.418081] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:588:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [91.418646] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [91.418797] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [91.419367] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [91.419548] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:588:HDMI-A-1] Failed to read TMDS config: -6
<7> [91.419565] i915 0000:00:02.0: [drm:intel_ddi_enable [i915]] [CONNECTOR:588:HDMI-A-1] Failed to configure sink scrambling/TMDS bit clock ratio
<7> [91.422899] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [91.423150] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:35:plane 1A]
<7> [91.428193] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:588:HDMI-A-1][ENCODER:587:DDI B/PHY B] Enable audio codec on [CRTC:171:pipe A], 64 bytes ELD
<7> [91.436523] 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> [91.436711] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)
<7> [91.436876] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<6> [91.437310] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 5 not expected
<6> [91.437318] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 0 not expected
<6> [91.437320] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 10 not expected
<6> [91.437322] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 6 not expected
<7> [91.437471] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:588:HDMI-A-1]
<7> [91.437752] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:171:pipe A]
<7> [91.438553] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_5
<7> [91.438850] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:585:pipe D]
<7> [91.439310] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_4
<7> [91.439724] i915 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0
Created at 2026-04-27 18:49:25