Results for igt@kms_flip@blocking-wf_vblank@a-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 18.54 seconds
Hostname
shard-tglu-2
Igt-Version
IGT-Version: 2.3-g33f5420a2 (x86_64) (Linux: 7.0.0-rc4-CI_DRM_18160-ga25d8c583e1e+ x86_64)
Out
Using IGT_SRANDOM=1773765028 for randomisation
Opened device: /dev/dri/card0
Using monotonic timestamps
Starting subtest: blocking-wf_vblank
Starting dynamic subtest: A-HDMI-A1
  1920x1080: 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5 
Expected frametime: 8335us; measured 8748.4us +- 3.793us accuracy 0.13%
vblank interval differs from modeline! expected 8334.6us, measured 8748us +- 3.793us, difference 413.8us (109.1 sigma)
Event vblank: expected 865, counted 880, passrate = 10.23%, encoder type 2
Expected frametime: 8335us; measured 8432.8us +- 3.152us accuracy 0.11%
vblank interval differs from modeline! expected 8334.6us, measured 8433us +- 3.152us, difference 98.2us (31.2 sigma)
Event vblank: expected 896, counted 900, passrate = 64.44%, encoder type 2
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1887 run_test()
  #3 ../tests/kms_flip.c:2122 __igt_unique____real_main2043()
  #4 ../tests/kms_flip.c:2043 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest A-HDMI-A1: FAIL (18.544s)
Err
Starting subtest: blocking-wf_vblank
Starting dynamic subtest: A-HDMI-A1
(kms_flip:2677) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1707:
(kms_flip:2677) CRITICAL: Failed assertion: !retried
Dynamic subtest A-HDMI-A1 failed.
**** DEBUG ****
(kms_flip:2677) DEBUG: vblank ts/seq: last 339.995941/28486, current 340.080963/28496: elapsed=84996.0us expected=87483.8us +- 437.4us, error 2.8%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 339.995941/28486, current 340.080963/28496: elapsed=84996.0us expected=87483.8us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 340.080963
last_received_ts = 340.080811
last_seq = 28496
current_ts = 340.165955
current_received_ts = 340.165833
current_seq = 28506
count = 81
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 340.080963/28496, current 340.165955/28506: elapsed=84992.0us expected=87483.8us +- 437.4us, error 2.8%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 340.080963/28496, current 340.165955/28506: elapsed=84992.0us expected=87483.8us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 340.165955
last_received_ts = 340.165833
last_seq = 28506
current_ts = 340.250946
current_received_ts = 340.250671
current_seq = 28516
count = 82
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 340.165955/28506, current 340.250946/28516: elapsed=84991.0us expected=87483.8us +- 437.4us, error 2.8%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 340.165955/28506, current 340.250946/28516: elapsed=84991.0us expected=87483.8us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 340.250946
last_received_ts = 340.250671
last_seq = 28516
current_ts = 340.335938
current_received_ts = 340.335815
current_seq = 28526
count = 83
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 340.250946/28516, current 340.335938/28526: elapsed=85002.0us expected=87483.8us +- 437.4us, error 2.8%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 340.250946/28516, current 340.335938/28526: elapsed=85002.0us expected=87483.8us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 340.335938
last_received_ts = 340.335815
last_seq = 28526
current_ts = 340.420929
current_received_ts = 340.420807
current_seq = 28536
count = 84
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 340.335938/28526, current 340.420929/28536: elapsed=84994.0us expected=87483.8us +- 437.4us, error 2.8%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 340.335938/28526, current 340.420929/28536: elapsed=84994.0us expected=87483.8us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 340.420929
last_received_ts = 340.420807
last_seq = 28536
current_ts = 340.505920
current_received_ts = 340.505798
current_seq = 28546
count = 85
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 340.420929/28536, current 340.505920/28546: elapsed=84992.0us expected=87483.8us +- 437.4us, error 2.8%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 340.420929/28536, current 340.505920/28546: elapsed=84992.0us expected=87483.8us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 340.505920
last_received_ts = 340.505798
last_seq = 28546
current_ts = 340.590912
current_received_ts = 340.590790
current_seq = 28556
count = 86
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 340.505920/28546, current 340.590912/28556: elapsed=84997.0us expected=87483.8us +- 437.4us, error 2.8%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 340.505920/28546, current 340.590912/28556: elapsed=84997.0us expected=87483.8us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 340.590912
last_received_ts = 340.590790
last_seq = 28556
current_ts = 340.675903
current_received_ts = 340.675781
current_seq = 28566
count = 87
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 340.590912/28556, current 340.675903/28566: elapsed=84993.0us expected=87483.8us +- 437.4us, error 2.8%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 340.590912/28556, current 340.675903/28566: elapsed=84993.0us expected=87483.8us
(kms_flip:2677) INFO: Event vblank: expected 865, counted 880, passrate = 10.23%, encoder type 2
(kms_flip:2677) DEBUG: dropped frames, expected 865, counted 880, passrate = 10.23%, encoder type 2
(kms_flip:2677) DEBUG: Retrying without a hotplug event
(kms_flip:2677) DEBUG: No stale events found
(kms_flip:2677) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:2677) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:2677) INFO: Expected frametime: 8335us; measured 8432.8us +- 3.152us accuracy 0.11%
(kms_flip:2677) INFO: vblank interval differs from modeline! expected 8334.6us, measured 8433us +- 3.152us, difference 98.2us (31.2 sigma)
(kms_flip:2677) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 344.046478
current_received_ts = 344.046265
current_seq = 28965
count = 0
seq_step = 10
(kms_flip:2677) DEBUG: name = vblank
last_ts = 344.046478
last_received_ts = 344.046265
last_seq = 28965
current_ts = 344.130798
current_received_ts = 344.130585
current_seq = 28975
count = 1
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 344.046478/28965, current 344.130798/28975: elapsed=84328.0us expected=84327.5us +- 421.6us, error 0.0%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 344.130798
last_received_ts = 344.130585
last_seq = 28975
current_ts = 344.215149
current_received_ts = 344.215057
current_seq = 28985
count = 2
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 344.130798/28975, current 344.215149/28985: elapsed=84332.0us expected=84327.5us +- 421.6us, error 0.0%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 344.215149
last_received_ts = 344.215057
last_seq = 28985
current_ts = 344.299469
current_received_ts = 344.299255
current_seq = 28995
count = 3
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 344.215149/28985, current 344.299469/28995: elapsed=84328.0us expected=84327.5us +- 421.6us, error 0.0%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 344.299469
last_received_ts = 344.299255
last_seq = 28995
current_ts = 344.383789
current_received_ts = 344.383667
current_seq = 29005
count = 4
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 344.299469/28995, current 344.383789/29005: elapsed=84332.0us expected=84327.5us +- 421.6us, error 0.0%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 344.383789
last_received_ts = 344.383667
last_seq = 29005
current_ts = 344.468140
current_received_ts = 344.468048
current_seq = 29015
count = 5
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 344.383789/29005, current 344.468140/29015: elapsed=84328.0us expected=84327.5us +- 421.6us, error 0.0%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 344.468140
last_received_ts = 344.468048
last_seq = 29015
current_ts = 344.552460
current_received_ts = 344.552368
current_seq = 29025
count = 6
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 344.468140/29015, current 344.552460/29025: elapsed=84327.0us expected=84327.5us +- 421.6us, error 0.0%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 344.552460
last_received_ts = 344.552368
last_seq = 29025
current_ts = 344.636780
current_received_ts = 344.636688
current_seq = 29035
count = 7
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 344.552460/29025, current 344.636780/29035: elapsed=84334.0us expected=84327.5us +- 421.6us, error 0.0%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 344.636780
last_received_ts = 344.636688
last_seq = 29035
current_ts = 344.721130
current_received_ts = 344.721039
current_seq = 29045
count = 8
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 344.636780/29035, current 344.721130/29045: elapsed=84328.0us expected=84327.5us +- 421.6us, error 0.0%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 344.721130
last_received_ts = 344.721039
last_seq = 29045
current_ts = 344.805450
current_received_ts = 344.805328
current_seq = 29055
count = 9
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 344.721130/29045, current 344.805450/29055: elapsed=84330.0us expected=84327.5us +- 421.6us, error 0.0%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 344.805450
last_received_ts = 344.805328
last_seq = 29055
current_ts = 344.889679
current_received_ts = 344.889587
current_seq = 29065
count = 10
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 344.805450/29055, current 344.889679/29065: elapsed=84235.0us expected=84327.5us +- 421.6us, error 0.1%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 344.889679
last_received_ts = 344.889587
last_seq = 29065
current_ts = 344.973877
current_received_ts = 344.973572
current_seq = 29075
count = 11
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 344.889679/29065, current 344.973877/29075: elapsed=84207.0us expected=84327.5us +- 421.6us, error 0.1%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 344.973877
last_received_ts = 344.973572
last_seq = 29075
current_ts = 345.058105
current_received_ts = 345.058075
current_seq = 29085
count = 12
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 344.973877/29075, current 345.058105/29085: elapsed=84219.0us expected=84327.5us +- 421.6us, error 0.1%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 345.058105
last_received_ts = 345.058075
last_seq = 29085
current_ts = 345.142334
current_received_ts = 345.142181
current_seq = 29095
count = 13
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 345.058105/29085, current 345.142334/29095: elapsed=84209.0us expected=84327.5us +- 421.6us, error 0.1%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 345.142334
last_received_ts = 345.142181
last_seq = 29095
current_ts = 345.226532
current_received_ts = 345.226410
current_seq = 29105
count = 14
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 345.142334/29095, current 345.226532/29105: elapsed=84208.0us expected=84327.5us +- 421.6us, error 0.1%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 345.226532
last_received_ts = 345.226410
last_seq = 29105
current_ts = 345.310730
current_received_ts = 345.310638
current_seq = 29115
count = 15
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 345.226532/29105, current 345.310730/29115: elapsed=84217.0us expected=84327.5us +- 421.6us, error 0.1%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 345.310730
last_received_ts = 345.310638
last_seq = 29115
current_ts = 345.394958
current_received_ts = 345.394836
current_seq = 29125
count = 16
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 345.310730/29115, current 345.394958/29125: elapsed=84210.0us expected=84327.5us +- 421.6us, error 0.1%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 345.394958
last_received_ts = 345.394836
last_seq = 29125
current_ts = 345.479156
current_received_ts = 345.479065
current_seq = 29135
count = 17
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 345.394958/29125, current 345.479156/29135: elapsed=84207.0us expected=84327.5us +- 421.6us, error 0.1%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 345.479156
last_received_ts = 345.479065
last_seq = 29135
current_ts = 345.563385
current_received_ts = 345.563293
current_seq = 29145
count = 18
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 345.479156/29135, current 345.563385/29145: elapsed=84217.0us expected=84327.5us +- 421.6us, error 0.1%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 345.563385
last_received_ts = 345.563293
last_seq = 29145
current_ts = 345.647583
current_received_ts = 345.647491
current_seq = 29155
count = 19
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 345.563385/29145, current 345.647583/29155: elapsed=84209.0us expected=84327.5us +- 421.6us, error 0.1%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 345.647583
last_received_ts = 345.647491
last_seq = 29155
current_ts = 345.731812
current_received_ts = 345.731689
current_seq = 29165
count = 20
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 345.647583/29155, current 345.731812/29165: elapsed=84210.0us expected=84327.5us +- 421.6us, error 0.1%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 345.731812
last_received_ts = 345.731689
last_seq = 29165
current_ts = 345.816010
current_received_ts = 345.815857
current_seq = 29175
count = 21
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 345.731812/29165, current 345.816010/29175: elapsed=84211.0us expected=84327.5us +- 421.6us, error 0.1%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 345.816010
last_received_ts = 345.815857
last_seq = 29175
current_ts = 345.900146
current_received_ts = 345.900055
current_seq = 29185
count = 22
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 345.816010/29175, current 345.900146/29185: elapsed=84126.0us expected=84327.5us +- 421.6us, error 0.2%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 345.900146
last_received_ts = 345.900055
last_seq = 29185
current_ts = 345.984253
current_received_ts = 345.984161
current_seq = 29195
count = 23
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 345.900146/29185, current 345.984253/29195: elapsed=84109.0us expected=84327.5us +- 421.6us, error 0.3%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 345.984253
last_received_ts = 345.984161
last_seq = 29195
current_ts = 346.068359
current_received_ts = 346.068268
current_seq = 29205
count = 24
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 345.984253/29195, current 346.068359/29205: elapsed=84108.0us expected=84327.5us +- 421.6us, error 0.3%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 346.068359
last_received_ts = 346.068268
last_seq = 29205
current_ts = 346.152466
current_received_ts = 346.152374
current_seq = 29215
count = 25
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 346.068359/29205, current 346.152466/29215: elapsed=84109.0us expected=84327.5us +- 421.6us, error 0.3%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 346.152466
last_received_ts = 346.152374
last_seq = 29215
current_ts = 346.236572
current_received_ts = 346.236450
current_seq = 29225
count = 26
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 346.152466/29215, current 346.236572/29225: elapsed=84107.0us expected=84327.5us +- 421.6us, error 0.3%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 346.236572
last_received_ts = 346.236450
last_seq = 29225
current_ts = 346.320679
current_received_ts = 346.320587
current_seq = 29235
count = 27
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 346.236572/29225, current 346.320679/29235: elapsed=84105.0us expected=84327.5us +- 421.6us, error 0.3%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 346.320679
last_received_ts = 346.320587
last_seq = 29235
current_ts = 346.404785
current_received_ts = 346.404633
current_seq = 29245
count = 28
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 346.320679/29235, current 346.404785/29245: elapsed=84114.0us expected=84327.5us +- 421.6us, error 0.3%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 346.404785
last_received_ts = 346.404633
last_seq = 29245
current_ts = 346.488892
current_received_ts = 346.488770
current_seq = 29255
count = 29
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 346.404785/29245, current 346.488892/29255: elapsed=84107.0us expected=84327.5us +- 421.6us, error 0.3%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 346.488892
last_received_ts = 346.488770
last_seq = 29255
current_ts = 346.572998
current_received_ts = 346.572876
current_seq = 29265
count = 30
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 346.488892/29255, current 346.572998/29265: elapsed=84110.0us expected=84327.5us +- 421.6us, error 0.3%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 346.572998
last_received_ts = 346.572876
last_seq = 29265
current_ts = 346.657104
current_received_ts = 346.656891
current_seq = 29275
count = 31
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 346.572998/29265, current 346.657104/29275: elapsed=84102.0us expected=84327.5us +- 421.6us, error 0.3%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 346.657104
last_received_ts = 346.656891
last_seq = 29275
current_ts = 346.741211
current_received_ts = 346.741089
current_seq = 29285
count = 32
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 346.657104/29275, current 346.741211/29285: elapsed=84114.0us expected=84327.5us +- 421.6us, error 0.3%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 346.741211
last_received_ts = 346.741089
last_seq = 29285
current_ts = 346.825317
current_received_ts = 346.825226
current_seq = 29295
count = 33
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 346.741211/29285, current 346.825317/29295: elapsed=84095.0us expected=84327.5us +- 421.6us, error 0.3%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 346.825317
last_received_ts = 346.825226
last_seq = 29295
current_ts = 346.909332
current_received_ts = 346.909241
current_seq = 29305
count = 34
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 346.825317/29295, current 346.909332/29305: elapsed=84023.0us expected=84327.5us +- 421.6us, error 0.4%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 346.909332
last_received_ts = 346.909241
last_seq = 29305
current_ts = 346.993347
current_received_ts = 346.993225
current_seq = 29315
count = 35
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 346.909332/29305, current 346.993347/29315: elapsed=84015.0us expected=84327.5us +- 421.6us, error 0.4%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 346.993347
last_received_ts = 346.993225
last_seq = 29315
current_ts = 347.077362
current_received_ts = 347.077240
current_seq = 29325
count = 36
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 346.993347/29315, current 347.077362/29325: elapsed=84022.0us expected=84327.5us +- 421.6us, error 0.4%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 347.077362
last_received_ts = 347.077240
last_seq = 29325
current_ts = 347.161377
current_received_ts = 347.161285
current_seq = 29335
count = 37
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 347.077362/29325, current 347.161377/29335: elapsed=84015.0us expected=84327.5us +- 421.6us, error 0.4%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 347.161377
last_received_ts = 347.161285
last_seq = 29335
current_ts = 347.245392
current_received_ts = 347.245239
current_seq = 29345
count = 38
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 347.161377/29335, current 347.245392/29345: elapsed=84018.0us expected=84327.5us +- 421.6us, error 0.4%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 347.245392
last_received_ts = 347.245239
last_seq = 29345
current_ts = 347.329437
current_received_ts = 347.329346
current_seq = 29355
count = 39
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 347.245392/29345, current 347.329437/29355: elapsed=84019.0us expected=84327.5us +- 421.6us, error 0.4%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 347.329437
last_received_ts = 347.329346
last_seq = 29355
current_ts = 347.413452
current_received_ts = 347.413361
current_seq = 29365
count = 40
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 347.329437/29355, current 347.413452/29365: elapsed=84017.0us expected=84327.5us +- 421.6us, error 0.4%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 347.413452
last_received_ts = 347.413361
last_seq = 29365
current_ts = 347.497467
current_received_ts = 347.497375
current_seq = 29375
count = 41
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 347.413452/29365, current 347.497467/29375: elapsed=84024.0us expected=84327.5us +- 421.6us, error 0.4%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 347.497467
last_received_ts = 347.497375
last_seq = 29375
current_ts = 347.581482
current_received_ts = 347.581390
current_seq = 29385
count = 42
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 347.497467/29375, current 347.581482/29385: elapsed=84016.0us expected=84327.5us +- 421.6us, error 0.4%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 347.581482
last_received_ts = 347.581390
last_seq = 29385
current_ts = 347.665497
current_received_ts = 347.665436
current_seq = 29395
count = 43
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 347.581482/29385, current 347.665497/29395: elapsed=84020.0us expected=84327.5us +- 421.6us, error 0.4%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 347.665497
last_received_ts = 347.665436
last_seq = 29395
current_ts = 347.749512
current_received_ts = 347.749420
current_seq = 29405
count = 44
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 347.665497/29395, current 347.749512/29405: elapsed=84017.0us expected=84327.5us +- 421.6us, error 0.4%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 347.749512
last_received_ts = 347.749420
last_seq = 29405
current_ts = 347.833527
current_received_ts = 347.833405
current_seq = 29415
count = 45
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 347.749512/29405, current 347.833527/29415: elapsed=84004.0us expected=84327.5us +- 421.6us, error 0.4%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 347.833527
last_received_ts = 347.833405
last_seq = 29415
current_ts = 347.917450
current_received_ts = 347.917328
current_seq = 29425
count = 46
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 347.833527/29415, current 347.917450/29425: elapsed=83938.0us expected=84327.5us +- 421.6us, error 0.5%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 347.917450
last_received_ts = 347.917328
last_seq = 29425
current_ts = 348.001404
current_received_ts = 348.001312
current_seq = 29435
count = 47
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 347.917450/29425, current 348.001404/29435: elapsed=83939.0us expected=84327.5us +- 421.6us, error 0.5%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 348.001404
last_received_ts = 348.001312
last_seq = 29435
current_ts = 348.085327
current_received_ts = 348.085236
current_seq = 29445
count = 48
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 348.001404/29435, current 348.085327/29445: elapsed=83941.0us expected=84327.5us +- 421.6us, error 0.5%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 348.085327
last_received_ts = 348.085236
last_seq = 29445
current_ts = 348.169281
current_received_ts = 348.169189
current_seq = 29455
count = 49
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 348.085327/29445, current 348.169281/29455: elapsed=83942.0us expected=84327.5us +- 421.6us, error 0.5%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 348.169281
last_received_ts = 348.169189
last_seq = 29455
current_ts = 348.253235
current_received_ts = 348.253143
current_seq = 29465
count = 50
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 348.169281/29455, current 348.253235/29465: elapsed=83937.0us expected=84327.5us +- 421.6us, error 0.5%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 348.253235
last_received_ts = 348.253143
last_seq = 29465
current_ts = 348.337158
current_received_ts = 348.337097
current_seq = 29475
count = 51
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 348.253235/29465, current 348.337158/29475: elapsed=83940.0us expected=84327.5us +- 421.6us, error 0.5%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 348.337158
last_received_ts = 348.337097
last_seq = 29475
current_ts = 348.421112
current_received_ts = 348.421021
current_seq = 29485
count = 52
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 348.337158/29475, current 348.421112/29485: elapsed=83937.0us expected=84327.5us +- 421.6us, error 0.5%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 348.421112
last_received_ts = 348.421021
last_seq = 29485
current_ts = 348.505035
current_received_ts = 348.504944
current_seq = 29495
count = 53
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 348.421112/29485, current 348.505035/29495: elapsed=83944.0us expected=84327.5us +- 421.6us, error 0.5%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 348.505035
last_received_ts = 348.504944
last_seq = 29495
current_ts = 348.588989
current_received_ts = 348.588776
current_seq = 29505
count = 54
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 348.505035/29495, current 348.588989/29505: elapsed=83937.0us expected=84327.5us +- 421.6us, error 0.5%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 348.588989
last_received_ts = 348.588776
last_seq = 29505
current_ts = 348.672913
current_received_ts = 348.672791
current_seq = 29515
count = 55
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 348.588989/29505, current 348.672913/29515: elapsed=83939.0us expected=84327.5us +- 421.6us, error 0.5%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 348.672913
last_received_ts = 348.672791
last_seq = 29515
current_ts = 348.756866
current_received_ts = 348.756775
current_seq = 29525
count = 56
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 348.672913/29515, current 348.756866/29525: elapsed=83942.0us expected=84327.5us +- 421.6us, error 0.5%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 348.756866
last_received_ts = 348.756775
last_seq = 29525
current_ts = 348.840790
current_received_ts = 348.840576
current_seq = 29535
count = 57
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 348.756866/29525, current 348.840790/29535: elapsed=83925.0us expected=84327.5us +- 421.6us, error 0.5%
(kms_flip:2677) DEBUG: name = vblank
last_ts = 348.840790
last_received_ts = 348.840576
last_seq = 29535
current_ts = 348.924652
current_received_ts = 348.924530
current_seq = 29545
count = 58
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 348.840790/29535, current 348.924652/29545: elapsed=83870.0us expected=84327.5us +- 421.6us, error 0.5%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 348.840790/29535, current 348.924652/29545: elapsed=83870.0us expected=84327.5us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 348.924652
last_received_ts = 348.924530
last_seq = 29545
current_ts = 349.008514
current_received_ts = 349.008209
current_seq = 29555
count = 59
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 348.924652/29545, current 349.008514/29555: elapsed=83867.0us expected=84327.5us +- 421.6us, error 0.5%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 348.924652/29545, current 349.008514/29555: elapsed=83867.0us expected=84327.5us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 349.008514
last_received_ts = 349.008209
last_seq = 29555
current_ts = 349.092407
current_received_ts = 349.092285
current_seq = 29565
count = 60
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 349.008514/29555, current 349.092407/29565: elapsed=83876.0us expected=84327.5us +- 421.6us, error 0.5%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 349.008514/29555, current 349.092407/29565: elapsed=83876.0us expected=84327.5us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 349.092407
last_received_ts = 349.092285
last_seq = 29565
current_ts = 349.176270
current_received_ts = 349.176147
current_seq = 29575
count = 61
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 349.092407/29565, current 349.176270/29575: elapsed=83865.0us expected=84327.5us +- 421.6us, error 0.5%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 349.092407/29565, current 349.176270/29575: elapsed=83865.0us expected=84327.5us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 349.176270
last_received_ts = 349.176147
last_seq = 29575
current_ts = 349.260132
current_received_ts = 349.260071
current_seq = 29585
count = 62
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 349.176270/29575, current 349.260132/29585: elapsed=83871.0us expected=84327.5us +- 421.6us, error 0.5%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 349.176270/29575, current 349.260132/29585: elapsed=83871.0us expected=84327.5us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 349.260132
last_received_ts = 349.260071
last_seq = 29585
current_ts = 349.343994
current_received_ts = 349.343842
current_seq = 29595
count = 63
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 349.260132/29585, current 349.343994/29595: elapsed=83874.0us expected=84327.5us +- 421.6us, error 0.5%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 349.260132/29585, current 349.343994/29595: elapsed=83874.0us expected=84327.5us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 349.343994
last_received_ts = 349.343842
last_seq = 29595
current_ts = 349.427887
current_received_ts = 349.427795
current_seq = 29605
count = 64
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 349.343994/29595, current 349.427887/29605: elapsed=83871.0us expected=84327.5us +- 421.6us, error 0.5%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 349.343994/29595, current 349.427887/29605: elapsed=83871.0us expected=84327.5us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 349.427887
last_received_ts = 349.427795
last_seq = 29605
current_ts = 349.511749
current_received_ts = 349.511658
current_seq = 29615
count = 65
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 349.427887/29605, current 349.511749/29615: elapsed=83871.0us expected=84327.5us +- 421.6us, error 0.5%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 349.427887/29605, current 349.511749/29615: elapsed=83871.0us expected=84327.5us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 349.511749
last_received_ts = 349.511658
last_seq = 29615
current_ts = 349.595612
current_received_ts = 349.595367
current_seq = 29625
count = 66
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 349.511749/29615, current 349.595612/29625: elapsed=83872.0us expected=84327.5us +- 421.6us, error 0.5%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 349.511749/29615, current 349.595612/29625: elapsed=83872.0us expected=84327.5us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 349.595612
last_received_ts = 349.595367
last_seq = 29625
current_ts = 349.679504
current_received_ts = 349.679382
current_seq = 29635
count = 67
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 349.595612/29625, current 349.679504/29635: elapsed=83869.0us expected=84327.5us +- 421.6us, error 0.5%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 349.595612/29625, current 349.679504/29635: elapsed=83869.0us expected=84327.5us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 349.679504
last_received_ts = 349.679382
last_seq = 29635
current_ts = 349.763367
current_received_ts = 349.763275
current_seq = 29645
count = 68
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 349.679504/29635, current 349.763367/29645: elapsed=83870.0us expected=84327.5us +- 421.6us, error 0.5%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 349.679504/29635, current 349.763367/29645: elapsed=83870.0us expected=84327.5us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 349.763367
last_received_ts = 349.763275
last_seq = 29645
current_ts = 349.847229
current_received_ts = 349.846863
current_seq = 29655
count = 69
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 349.763367/29645, current 349.847229/29655: elapsed=83853.0us expected=84327.5us +- 421.6us, error 0.6%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 349.763367/29645, current 349.847229/29655: elapsed=83853.0us expected=84327.5us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 349.847229
last_received_ts = 349.846863
last_seq = 29655
current_ts = 349.931030
current_received_ts = 349.930939
current_seq = 29665
count = 70
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 349.847229/29655, current 349.931030/29665: elapsed=83809.0us expected=84327.5us +- 421.6us, error 0.6%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 349.847229/29655, current 349.931030/29665: elapsed=83809.0us expected=84327.5us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 349.931030
last_received_ts = 349.930939
last_seq = 29665
current_ts = 350.014832
current_received_ts = 350.014740
current_seq = 29675
count = 71
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 349.931030/29665, current 350.014832/29675: elapsed=83814.0us expected=84327.5us +- 421.6us, error 0.6%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 349.931030/29665, current 350.014832/29675: elapsed=83814.0us expected=84327.5us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 350.014832
last_received_ts = 350.014740
last_seq = 29675
current_ts = 350.098633
current_received_ts = 350.098541
current_seq = 29685
count = 72
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 350.014832/29675, current 350.098633/29685: elapsed=83809.0us expected=84327.5us +- 421.6us, error 0.6%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 350.014832/29675, current 350.098633/29685: elapsed=83809.0us expected=84327.5us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 350.098633
last_received_ts = 350.098541
last_seq = 29685
current_ts = 350.182465
current_received_ts = 350.182373
current_seq = 29695
count = 73
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 350.098633/29685, current 350.182465/29695: elapsed=83807.0us expected=84327.5us +- 421.6us, error 0.6%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 350.098633/29685, current 350.182465/29695: elapsed=83807.0us expected=84327.5us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 350.182465
last_received_ts = 350.182373
last_seq = 29695
current_ts = 350.266266
current_received_ts = 350.266174
current_seq = 29705
count = 74
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 350.182465/29695, current 350.266266/29705: elapsed=83814.0us expected=84327.5us +- 421.6us, error 0.6%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 350.182465/29695, current 350.266266/29705: elapsed=83814.0us expected=84327.5us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 350.266266
last_received_ts = 350.266174
last_seq = 29705
current_ts = 350.350067
current_received_ts = 350.349945
current_seq = 29715
count = 75
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 350.266266/29705, current 350.350067/29715: elapsed=83810.0us expected=84327.5us +- 421.6us, error 0.6%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 350.266266/29705, current 350.350067/29715: elapsed=83810.0us expected=84327.5us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 350.350067
last_received_ts = 350.349945
last_seq = 29715
current_ts = 350.433899
current_received_ts = 350.433777
current_seq = 29725
count = 76
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 350.350067/29715, current 350.433899/29725: elapsed=83808.0us expected=84327.5us +- 421.6us, error 0.6%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 350.350067/29715, current 350.433899/29725: elapsed=83808.0us expected=84327.5us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 350.433899
last_received_ts = 350.433777
last_seq = 29725
current_ts = 350.517700
current_received_ts = 350.517609
current_seq = 29735
count = 77
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 350.433899/29725, current 350.517700/29735: elapsed=83809.0us expected=84327.5us +- 421.6us, error 0.6%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 350.433899/29725, current 350.517700/29735: elapsed=83809.0us expected=84327.5us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 350.517700
last_received_ts = 350.517609
last_seq = 29735
current_ts = 350.601501
current_received_ts = 350.601410
current_seq = 29745
count = 78
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 350.517700/29735, current 350.601501/29745: elapsed=83813.0us expected=84327.5us +- 421.6us, error 0.6%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 350.517700/29735, current 350.601501/29745: elapsed=83813.0us expected=84327.5us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 350.601501
last_received_ts = 350.601410
last_seq = 29745
current_ts = 350.685303
current_received_ts = 350.685120
current_seq = 29755
count = 79
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 350.601501/29745, current 350.685303/29755: elapsed=83804.0us expected=84327.5us +- 421.6us, error 0.6%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 350.601501/29745, current 350.685303/29755: elapsed=83804.0us expected=84327.5us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 350.685303
last_received_ts = 350.685120
last_seq = 29755
current_ts = 350.769135
current_received_ts = 350.769043
current_seq = 29765
count = 80
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 350.685303/29755, current 350.769135/29765: elapsed=83814.0us expected=84327.5us +- 421.6us, error 0.6%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 350.685303/29755, current 350.769135/29765: elapsed=83814.0us expected=84327.5us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 350.769135
last_received_ts = 350.769043
last_seq = 29765
current_ts = 350.852905
current_received_ts = 350.852814
current_seq = 29775
count = 81
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 350.769135/29765, current 350.852905/29775: elapsed=83787.0us expected=84327.5us +- 421.6us, error 0.6%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 350.769135/29765, current 350.852905/29775: elapsed=83787.0us expected=84327.5us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 350.852905
last_received_ts = 350.852814
last_seq = 29775
current_ts = 350.936676
current_received_ts = 350.936554
current_seq = 29785
count = 82
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 350.852905/29775, current 350.936676/29785: elapsed=83761.0us expected=84327.5us +- 421.6us, error 0.7%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 350.852905/29775, current 350.936676/29785: elapsed=83761.0us expected=84327.5us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 350.936676
last_received_ts = 350.936554
last_seq = 29785
current_ts = 351.020416
current_received_ts = 351.020294
current_seq = 29795
count = 83
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 350.936676/29785, current 351.020416/29795: elapsed=83752.0us expected=84327.5us +- 421.6us, error 0.7%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 350.936676/29785, current 351.020416/29795: elapsed=83752.0us expected=84327.5us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 351.020416
last_received_ts = 351.020294
last_seq = 29795
current_ts = 351.104187
current_received_ts = 351.104095
current_seq = 29805
count = 84
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 351.020416/29795, current 351.104187/29805: elapsed=83758.0us expected=84327.5us +- 421.6us, error 0.7%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 351.020416/29795, current 351.104187/29805: elapsed=83758.0us expected=84327.5us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 351.104187
last_received_ts = 351.104095
last_seq = 29805
current_ts = 351.187958
current_received_ts = 351.187805
current_seq = 29815
count = 85
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 351.104187/29805, current 351.187958/29815: elapsed=83762.0us expected=84327.5us +- 421.6us, error 0.7%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 351.104187/29805, current 351.187958/29815: elapsed=83762.0us expected=84327.5us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 351.187958
last_received_ts = 351.187805
last_seq = 29815
current_ts = 351.271698
current_received_ts = 351.271576
current_seq = 29825
count = 86
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 351.187958/29815, current 351.271698/29825: elapsed=83752.0us expected=84327.5us +- 421.6us, error 0.7%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 351.187958/29815, current 351.271698/29825: elapsed=83752.0us expected=84327.5us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 351.271698
last_received_ts = 351.271576
last_seq = 29825
current_ts = 351.355469
current_received_ts = 351.355347
current_seq = 29835
count = 87
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 351.271698/29825, current 351.355469/29835: elapsed=83763.0us expected=84327.5us +- 421.6us, error 0.7%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 351.271698/29825, current 351.355469/29835: elapsed=83763.0us expected=84327.5us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 351.355469
last_received_ts = 351.355347
last_seq = 29835
current_ts = 351.439209
current_received_ts = 351.439087
current_seq = 29845
count = 88
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 351.355469/29835, current 351.439209/29845: elapsed=83755.0us expected=84327.5us +- 421.6us, error 0.7%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 351.355469/29835, current 351.439209/29845: elapsed=83755.0us expected=84327.5us
(kms_flip:2677) DEBUG: name = vblank
last_ts = 351.439209
last_received_ts = 351.439087
last_seq = 29845
current_ts = 351.522980
current_received_ts = 351.522858
current_seq = 29855
count = 89
seq_step = 10
(kms_flip:2677) DEBUG: vblank ts/seq: last 351.439209/29845, current 351.522980/29855: elapsed=83755.0us expected=84327.5us +- 421.6us, error 0.7%
(kms_flip:2677) DEBUG: inconsistent vblank ts/seq: last 351.439209/29845, current 351.522980/29855: elapsed=83755.0us expected=84327.5us
(kms_flip:2677) INFO: Event vblank: expected 896, counted 900, passrate = 64.44%, encoder type 2
(kms_flip:2677) DEBUG: dropped frames, expected 896, counted 900, passrate = 64.44%, encoder type 2
(kms_flip:2677) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1707:
(kms_flip:2677) CRITICAL: Failed assertion: !retried
(kms_flip:2677) igt_core-INFO: Stack trace:
(kms_flip:2677) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2677) igt_core-INFO:   #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
(kms_flip:2677) igt_core-INFO:   #2 ../tests/kms_flip.c:1887 run_test()
(kms_flip:2677) igt_core-INFO:   #3 ../tests/kms_flip.c:2122 __igt_unique____real_main2043()
(kms_flip:2677) igt_core-INFO:   #4 ../tests/kms_flip.c:2043 main()
(kms_flip:2677) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2677) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2677) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-HDMI-A1: FAIL (18.544s)
Dmesg
<6> [347.553992] Console: switching to colour dummy device 80x25
<6> [347.554459] [IGT] kms_flip: executing
<7> [347.559446] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [347.560177] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [347.560511] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [347.569991] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:597:DP-1]
<7> [347.570000] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:597:DP-1]
<7> [347.570181] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling TC_cold_off
<7> [347.570346] i915 0000:00:02.0: [drm:tgl_tc_cold_request [i915]] TC cold block succeeded
<7> [347.570612] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling TC_cold_off
<7> [347.570786] i915 0000:00:02.0: [drm:tgl_tc_cold_request [i915]] TC cold unblock succeeded
<7> [347.570968] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling TC_cold_off
<7> [347.571134] i915 0000:00:02.0: [drm:tgl_tc_cold_request [i915]] TC cold block succeeded
<7> [347.571465] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port D/TC#1: TC port mode reset (disconnected -> legacy) pin assignment: - max lanes: 4
<7> [347.575788] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:597:DP-1] disconnected
<7> [347.576188] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:DP-2]
<7> [347.576192] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:607:DP-2]
<7> [347.576519] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port E/TC#2: TC port mode reset (disconnected -> tbt-alt) pin assignment: - max lanes: 4
<7> [347.576776] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:DP-2] disconnected
<7> [347.577207] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:616:DP-3]
<7> [347.577212] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:616:DP-3]
<7> [347.577543] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port F/TC#3: TC port mode reset (disconnected -> tbt-alt) pin assignment: - max lanes: 4
<7> [347.577807] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:616:DP-3] disconnected
<7> [347.578224] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:625:DP-4]
<7> [347.578228] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:625:DP-4]
<7> [347.578571] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port G/TC#4: TC port mode reset (disconnected -> tbt-alt) pin assignment: - max lanes: 4
<7> [347.578835] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:625:DP-4] disconnected
<7> [347.579460] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:587:HDMI-A-1]
<7> [347.579471] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:587:HDMI-A-1]
<7> [347.606279] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] Supported Monitor Refresh rate range is 0 Hz - 0 Hz
<7> [347.606290] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] HDMI: DVI dual 0, max TMDS clock 300000 kHz
<7> [347.606294] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] No deep color support on this HDMI sink.
<7> [347.606297] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] HF-VSDB: max TMDS clock: 600000 KHz, HDMI 2.1 support: no, DSC 1.2 support: no
<7> [347.606300] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] Assigning EDID-1.4 digital sink color depth as 8 bpc.
<7> [347.606303] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] ELD monitor fitHeadlessGS
<7> [347.606306] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] HDMI: latency present 0 0, video latency 0 0, audio latency 0 0
<7> [347.606309] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] ELD size 64, SAD count 10
<7> [347.606721] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0040 w(1)
<7> [347.606905] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [347.607461] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0040 w(1)
<7> [347.607645] i915 0000:00:02.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [347.608459] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:587:HDMI-A-1] probed modes:
<7> [347.608465] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5
<7> [347.608468] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "4096x2160": 60 594000 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5
<7> [347.608472] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "4096x2160": 60 593407 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5
<7> [347.608475] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "4096x2160": 30 297000 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5
<7> [347.608478] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "4096x2160": 30 296703 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5
<7> [347.608481] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 60 594000 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5
<7> [347.608484] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 60 593407 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5
<7> [347.608487] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 30 297000 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5
<7> [347.608490] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 30 296703 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5
<7> [347.608493] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 25 297000 3840 4896 4984 5280 2160 2168 2178 2250 0x40 0x5
<7> [347.608496] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 24 297000 3840 5116 5204 5500 2160 2168 2178 2250 0x40 0x5
<7> [347.608499] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 24 296703 3840 5116 5204 5500 2160 2168 2178 2250 0x40 0x5
<7> [347.608502] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3440x1440": 90 476140 3440 3448 3480 3520 1440 1441 1449 1503 0x40 0xa
<7> [347.608505] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1600": 75 443250 2560 2768 3048 3536 1600 1603 1609 1672 0x40 0x6
<7> [347.608508] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1600": 60 348500 2560 2752 3032 3504 1600 1603 1609 1658 0x40 0x6
<7> [347.608511] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1440": 120 497770 2560 2608 2640 2720 1440 1443 1448 1525 0x40 0xa
<7> [347.608514] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2048x1536": 60 267027 2048 2200 2424 2800 1536 1537 1540 1589 0x0 0x6
<7> [347.608517] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1080": 120 495000 2560 3108 3152 3300 1080 1084 1089 1250 0x40 0x5
<7> [347.608520] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1080": 120 494505 2560 3108 3152 3300 1080 1084 1089 1250 0x40 0x5
<7> [347.608523] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1440": 75 297000 1920 2064 2288 2640 1440 1441 1444 1500 0x40 0x6
<7> [347.608525] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1440": 60 234000 1920 2048 2256 2600 1440 1441 1444 1500 0x40 0x6
<7> [347.608528] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2160x1200": 90 261420 2160 2208 2240 2320 1200 1203 1208 1252 0x40 0xa
<7> [347.608532] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1856x1392": 75 288000 1856 1984 2208 2560 1392 1393 1396 1500 0x40 0x6
<7> [347.608535] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1856x1392": 60 218250 1856 1952 2176 2528 1392 1393 1396 1439 0x40 0x6
<7> [347.608538] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1792x1344": 75 261000 1792 1888 2104 2456 1344 1345 1348 1417 0x40 0x6
<7> [347.608541] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1792x1344": 60 204750 1792 1920 2120 2448 1344 1345 1348 1394 0x40 0x6
<7> [347.608544] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2048x1152": 60 162000 2048 2074 2154 2250 1152 1153 1156 1200 0x40 0x5
<7> [347.608547] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1200": 120 317250 1920 1968 2000 2080 1200 1203 1209 1271 0x40 0xa
<7> [347.608550] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1200": 75 245250 1920 2056 2264 2608 1200 1203 1209 1255 0x40 0x6
<7> [347.608553] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1200": 60 193250 1920 2056 2256 2592 1200 1203 1209 1245 0x40 0x6
<7> [347.608555] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 120 297000 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [347.608558] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 120 296703 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [347.608561] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 75 220597 1920 2056 2264 2608 1080 1081 1084 1128 0x0 0x6
<7> [347.608564] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [347.608567] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 60 148352 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [347.608570] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x1200": 75 202500 1600 1664 1856 2160 1200 1201 1204 1250 0x40 0x5
<7> [347.608573] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x1200": 70 189000 1600 1664 1856 2160 1200 1201 1204 1250 0x40 0x5
<7> [347.608576] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x1200": 65 175500 1600 1664 1856 2160 1200 1201 1204 1250 0x40 0x5
<7> [347.608579] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x1200": 60 162000 1600 1664 1856 2160 1200 1201 1204 1250 0x40 0x5
<7> [347.608582] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1680x1050": 75 187000 1680 1800 1976 2272 1050 1053 1059 1099 0x40 0x6
<7> [347.608585] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1680x1050": 60 146250 1680 1784 1960 2240 1050 1053 1059 1089 0x40 0x6
<7> [347.608588] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1680x945": 60 131481 1680 1784 1960 2240 945 946 949 978 0x0 0x6
<7> [347.608591] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1400x1050": 75 156000 1400 1504 1648 1896 1050 1053 1057 1099 0x40 0x6
<7> [347.608594] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1400x1050": 60 121750 1400 1488 1632 1864 1050 1053 1057 1089 0x40 0x6
<7> [347.608597] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x900": 60 108000 1600 1624 1704 1800 900 901 904 1000 0x40 0x5
<7> [347.608600] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x1024": 75 135000 1280 1296 1440 1688 1024 1025 1028 1066 0x40 0x5
<7> [347.608603] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x1024": 60 108000 1280 1328 1440 1688 1024 1025 1028 1066 0x40 0x5
<7> [347.608606] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1440x900": 75 136750 1440 1536 1688 1936 900 903 909 942 0x40 0x6
<7> [347.608609] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1440x900": 60 106500 1440 1520 1672 1904 900 903 909 934 0x40 0x6
<7> [347.608612] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x960": 60 108000 1280 1376 1488 1800 960 961 964 1000 0x40 0x5
<7> [347.608615] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1360x768": 60 84963 1366 1431 1567 1776 768 771 781 798 0x0 0x6
<7> [347.608618] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1360x768": 60 85500 1360 1424 1536 1792 768 771 777 795 0x40 0x5
<7> [347.608621] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x800": 75 106500 1280 1360 1488 1696 800 803 809 838 0x40 0x6
<7> [347.608624] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x800": 60 83500 1280 1352 1480 1680 800 803 809 831 0x40 0x6
<7> [347.608626] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1152x864": 75 108000 1152 1216 1344 1600 864 865 868 900 0x40 0x5
<7> [347.608629] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x768": 75 102250 1280 1360 1488 1696 768 771 778 805 0x40 0x6
<7> [347.608632] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x768": 60 79500 1280 1344 1472 1664 768 771 778 798 0x40 0x6
<7> [347.608635] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74250 1280 1390 1430 1650 720 725 730 750 0x40 0x5
<7> [347.608638] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 75 78750 1024 1040 1136 1312 768 769 772 800 0x40 0x5
<7> [347.608641] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 70 75000 1024 1048 1184 1328 768 771 777 806 0x40 0xa
<7> [347.608644] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [347.608647] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x576": 60 46970 1024 1064 1168 1312 576 577 580 597 0x0 0x6
<7> [347.608650] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "832x624": 75 57284 832 864 928 1152 624 625 628 667 0x40 0xa
<7> [347.608653] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 75 49500 800 816 896 1056 600 601 604 625 0x40 0x5
<7> [347.608656] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 72 50000 800 856 976 1040 600 637 643 666 0x40 0x5
<7> [347.608659] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5
<7> [347.608662] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 56 36000 800 824 896 1024 600 601 603 625 0x40 0x5
<7> [347.608664] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "848x480": 60 33750 848 864 976 1088 480 486 494 517 0x40 0x5
<7> [347.608667] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 75 31500 640 656 720 840 480 481 484 500 0x40 0xa
<7> [347.608671] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 73 31500 640 664 704 832 480 489 492 520 0x40 0xa
<7> [347.608673] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25200 640 656 752 800 480 490 492 525 0x40 0xa
<7> [347.608676] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7> [347.608679] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "720x400": 70 28320 720 738 846 900 400 412 414 449 0x40 0x6
<7> [347.608726] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:597:DP-1]
<7> [347.608731] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:597:DP-1]
<7> [347.613098] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:597:DP-1] disconnected
<7> [347.613118] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:DP-2]
<7> [347.613121] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:607:DP-2]
<7> [347.613457] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:DP-2] disconnected
<7> [347.613472] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:616:DP-3]
<7> [347.613476] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:616:DP-3]
<7> [347.613788] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:616:DP-3] disconnected
<7> [347.613801] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:625:DP-4]
<7> [347.613804] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:625:DP-4]
<7> [347.614117] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:625:DP-4] disconnected
<6> [347.615005] [IGT] kms_flip: starting subtest blocking-wf_vblank
<6> [347.618059] [IGT] kms_flip: starting dynamic subtest A-HDMI-A1
<7> [347.618521] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:635]
<7> [347.618629] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:637]
<7> [347.663854] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:170:pipe A]
<7> [347.663981] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [347.664231] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [347.664445] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [347.664651] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [347.664851] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [347.665077] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [347.665313] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [347.665551] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2080, found 0)
<7> [347.665768] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [347.666003] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2080, found 0)
<7> [347.666209] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1968, found 0)
<7> [347.666410] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2000, found 0)
<7> [347.666609] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [347.666855] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [347.667085] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [347.667297] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [347.667490] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1144, found 0)
<7> [347.667647] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1144, found 0)
<7> [347.667810] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [347.668022] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2080, found 0)
<7> [347.668182] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [347.668348] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2080, found 0)
<7> [347.668514] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1968, found 0)
<7> [347.668671] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2000, found 0)
<7> [347.668837] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [347.668996] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [347.669188] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [347.669353] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [347.669517] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1144, found 0)
<7> [347.669673] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1144, found 0)
<7> [347.669857] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [347.670013] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [347.670170] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [347.670363] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [347.670520] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [347.670684] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [347.670866] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 285500, found 0)
<7> [347.671023] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 285500, found 0)
<7> [347.671179] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in port_clock (expected 285500, found 0)
<7> [347.671336] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [347.671529] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in avi infoframe
<7> [347.671694] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [347.671857] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [347.671860] i915 0000:00:02.0: colorspace: RGB
<7> [347.671862] i915 0000:00:02.0: scan mode: Underscan
<7> [347.671863] i915 0000:00:02.0: colorimetry: No Data
<7> [347.671865] i915 0000:00:02.0: picture aspect: No Data
<7> [347.671866] i915 0000:00:02.0: active aspect: Same as Picture
<7> [347.671868] i915 0000:00:02.0: itc: No Data
<7> [347.671869] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [347.671871] i915 0000:00:02.0: quantization range: Full
<7> [347.671872] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [347.671874] i915 0000:00:02.0: video code: 0
<7> [347.671875] i915 0000:00:02.0: ycc quantization range: Full
<7> [347.671876] i915 0000:00:02.0: hdmi content type: Graphics
<7> [347.671878] i915 0000:00:02.0: pixel repeat: 0
<7> [347.671879] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [347.671881] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [347.672046] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in spd infoframe
<7> [347.672207] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [347.672369] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [347.672371] i915 0000:00:02.0: vendor: Intel
<7> [347.672372] i915 0000:00:02.0: product: Integrated gfx
<7> [347.672374] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [347.672376] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [347.672536] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hdmi infoframe
<7> [347.672749] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [347.672907] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [347.672910] i915 0000:00:02.0: empty frame
<7> [347.672911] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [347.673076] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] fastset requirement not met, forcing full modeset
<7> [347.673245] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:170:pipe A] releasing DPLL 0
<7> [347.673442] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [347.673612] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:170:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [347.673811] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 1993) -> ( 0 - 0), size 1993 -> 0
<7> [347.673997] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:164:cursor A] ddb (1993 - 2048) -> ( 0 - 0), size 55 -> 0
<7> [347.674152] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34: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> [347.674318] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] lines 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [347.674483] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] blocks 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [347.674662] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] 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> [347.674839] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:170:pipe A] data rate 0 num active planes 0
<7> [347.675005] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 0
<7> [347.675225] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:170:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [347.675415] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 17844 kHz -> 0 kHz
<7> [347.675621] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [347.675792] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz
<7> [347.675952] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [347.676112] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:170:pipe A] enable: no [modeset]
<7> [347.676308] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:plane 1A] fb: [NOFB], visible: no
<7> [347.676477] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [347.676644] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [347.676808] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [347.676965] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [347.677124] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:plane 6A] fb: [NOFB], visible: no
<7> [347.677294] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:154:plane 7A] fb: [NOFB], visible: no
<7> [347.677488] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:164:cursor A] fb: [NOFB], visible: no
<7> [347.677735] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:587:HDMI-A-1][ENCODER:586:DDI B/PHY B] Disable audio codec on [CRTC:170:pipe A]
<7> [347.691707] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B
<7> [347.702058] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:587:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [347.702651] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [347.702859] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [347.704267] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [347.704588] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:587:HDMI-A-1] Failed to read TMDS config: -6
<7> [347.704613] i915 0000:00:02.0: [drm:intel_ddi_disable [i915]] [CONNECTOR:587:HDMI-A-1] Failed to reset sink scrambling/TMDS bit clock ratio
<7> [347.704888] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [347.708873] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [347.709185] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:170:pipe A]
<7> [347.709449] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [347.709689] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:34:plane 1A]
<7> [347.709935] 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> [347.710214] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:586:DDI B/PHY B]
<7> [347.710417] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:596:DDI TC1/PHY TC1]
<7> [347.710596] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:598:DP-MST A]
<7> [347.710814] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST B]
<7> [347.710992] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST C]
<7> [347.711167] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST D]
<7> [347.711341] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI TC2/PHY TC2]
<7> [347.711514] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:608:DP-MST A]
<7> [347.711687] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST B]
<7> [347.711919] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST C]
<7> [347.712096] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST D]
<7> [347.712271] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DDI TC3/PHY TC3]
<7> [347.712444] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:617:DP-MST A]
<7> [347.712616] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST B]
<7> [347.712844] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST C]
<7> [347.713017] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST D]
<7> [347.713189] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DDI TC4/PHY TC4]
<7> [347.713362] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST A]
<7> [347.713533] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST B]
<7> [347.713737] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST C]
<7> [347.713910] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST D]
<7> [347.714081] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:587:HDMI-A-1]
<7> [347.714460] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [347.714728] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [347.714912] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [347.715097] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:170:pipe A]
<7> [347.715549] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:308:pipe B]
<7> [347.715689] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:446:pipe C]
<7> [347.715791] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:584:pipe D]
<7> [347.715904] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:170:pipe A]
<7> [347.715936] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:587:HDMI-A-1]
<7> [347.716016] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:587:HDMI-A-1] Limiting target display pipe bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [347.716196] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [347.716356] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [347.716544] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:586:DDI B/PHY B] [CRTC:170:pipe A]
<7> [347.716704] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [347.716866] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [347.717030] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [347.717194] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [347.717354] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [347.717524] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [347.717683] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [347.717840] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2080)
<7> [347.717998] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [347.718156] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2080)
<7> [347.718312] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1968)
<7> [347.718502] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2000)
<7> [347.718734] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [347.718913] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [347.719069] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [347.719225] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [347.719401] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1144)
<7> [347.719561] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1144)
<7> [347.719717] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [347.719879] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2080)
<7> [347.720035] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [347.720193] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2080)
<7> [347.720378] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1968)
<7> [347.720535] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2000)
<7> [347.720691] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [347.720846] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [347.721003] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [347.721158] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [347.721328] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1144)
<7> [347.721485] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1144)
<7> [347.721641] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [347.721797] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [347.721953] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [347.722109] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [347.722276] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [347.722434] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [347.722590] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 285500)
<7> [347.722746] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 285500)
<7> [347.722901] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in port_clock (expected 0, found 285500)
<7> [347.723058] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [347.723228] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in avi infoframe
<7> [347.723383] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [347.723540] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [347.723695] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [347.723697] i915 0000:00:02.0: colorspace: RGB
<7> [347.723699] i915 0000:00:02.0: scan mode: Underscan
<7> [347.723700] i915 0000:00:02.0: colorimetry: No Data
<7> [347.723702] i915 0000:00:02.0: picture aspect: No Data
<7> [347.723703] i915 0000:00:02.0: active aspect: Same as Picture
<7> [347.723705] i915 0000:00:02.0: itc: No Data
<7> [347.723706] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [347.723708] i915 0000:00:02.0: quantization range: Full
<7> [347.723709] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [347.723711] i915 0000:00:02.0: video code: 0
<7> [347.723712] i915 0000:00:02.0: ycc quantization range: Full
<7> [347.723713] i915 0000:00:02.0: hdmi content type: Graphics
<7> [347.723715] i915 0000:00:02.0: pixel repeat: 0
<7> [347.723716] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [347.723718] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in spd infoframe
<7> [347.723875] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [347.724030] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [347.724206] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [347.724208] i915 0000:00:02.0: vendor: Intel
<7> [347.724209] i915 0000:00:02.0: product: Integrated gfx
<7> [347.724211] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [347.724213] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hdmi infoframe
<7> [347.724368] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [347.724524] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [347.724686] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [347.724688] i915 0000:00:02.0: empty frame
<7> [347.724690] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] fastset requirement not met, forcing full modeset
<7> [347.724894] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [347.725074] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:170:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x1
<7> [347.725305] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 0) -> ( 0 - 1993), size 0 -> 1993
<7> [347.725459] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:164:cursor A] ddb ( 0 - 0) -> (1993 - 2048), size 0 -> 55
<7> [347.725612] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34: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> [347.725765] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34: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> [347.725917] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34: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> [347.726085] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34: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> [347.726266] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:170:pipe A] data rate 1142000 num active planes 1
<7> [347.726430] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 1142
<7> [347.726589] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [347.726768] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:170:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [347.726958] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 17844 kHz
<7> [347.727147] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [347.727305] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [347.727463] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [347.727622] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:170:pipe A] allocated DPLL 0
<7> [347.727788] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:170:pipe A] reserving DPLL 0
<7> [347.727976] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:170:pipe A] enable: yes [modeset]
<7> [347.728139] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [347.728300] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [347.728461] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [347.728620] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [347.728778] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [347.728954] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [347.729112] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [347.729269] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x0
<7> [347.729426] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [347.729428] i915 0000:00:02.0: colorspace: RGB
<7> [347.729429] i915 0000:00:02.0: scan mode: Underscan
<7> [347.729431] i915 0000:00:02.0: colorimetry: No Data
<7> [347.729432] i915 0000:00:02.0: picture aspect: No Data
<7> [347.729434] i915 0000:00:02.0: active aspect: Same as Picture
<7> [347.729435] i915 0000:00:02.0: itc: No Data
<7> [347.729436] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [347.729438] i915 0000:00:02.0: quantization range: Full
<7> [347.729439] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [347.729441] i915 0000:00:02.0: video code: 0
<7> [347.729442] i915 0000:00:02.0: ycc quantization range: Full
<7> [347.729444] i915 0000:00:02.0: hdmi content type: Graphics
<7> [347.729445] i915 0000:00:02.0: pixel repeat: 0
<7> [347.729447] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [347.729448] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [347.729450] i915 0000:00:02.0: vendor: Intel
<7> [347.729452] i915 0000:00:02.0: product: Integrated gfx
<7> [347.729453] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [347.729455] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [347.729457] i915 0000:00:02.0: empty frame
<7> [347.729458] 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> [347.729616] 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> [347.729772] 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> [347.729945] 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> [347.730102] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [347.730258] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [347.730417] 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> [347.730577] 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> [347.730732] 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> [347.730907] 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> [347.731064] 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> [347.731222] 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> [347.731379] 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> [347.731536] 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> [347.731697] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 285500, pipe src: 1920x1080+0+0, pixel rate 285500, min cdclk 192000
<7> [347.731861] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [347.732017] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [347.732173] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [347.732329] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [347.732485] 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> [347.732643] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [347.732815] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [347.732971] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [347.733129] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [347.733284] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [347.733440] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [347.733597] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [347.733769] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [347.733933] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [347.734092] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [347.734252] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [347.734411] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [347.734575] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [347.734734] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:plane 1A] fb: [FB:635] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [347.734893] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [347.735050] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [347.735209] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [347.735366] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [347.735533] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [347.735692] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [347.735848] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:plane 6A] fb: [NOFB], visible: no
<7> [347.736003] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:154:plane 7A] fb: [NOFB], visible: no
<7> [347.736158] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:164:cursor A] fb: [NOFB], visible: no
<7> [347.736555] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [347.736751] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [347.736936] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [347.736969] 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> [347.737231] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:586:DDI B/PHY B]
<7> [347.737502] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:596:DDI TC1/PHY TC1]
<7> [347.737722] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:598:DP-MST A]
<7> [347.737923] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST B]
<7> [347.738082] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST C]
<7> [347.738250] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST D]
<7> [347.738450] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI TC2/PHY TC2]
<7> [347.738614] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:608:DP-MST A]
<7> [347.738781] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST B]
<7> [347.738941] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST C]
<7> [347.739097] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST D]
<7> [347.739279] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DDI TC3/PHY TC3]
<7> [347.739463] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:617:DP-MST A]
<7> [347.739623] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST B]
<7> [347.739811] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST C]
<7> [347.739970] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST D]
<7> [347.740137] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DDI TC4/PHY TC4]
<7> [347.740370] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST A]
<7> [347.740553] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST B]
<7> [347.740732] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST C]
<7> [347.740911] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST D]
<7> [347.741283] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [347.741326] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<7> [347.741262] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [347.741545] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:170:pipe A]
<7> [347.741734] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [347.741982] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [347.742262] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [347.742627] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:587:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [347.743222] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [347.743393] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [347.743939] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [347.744160] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:587:HDMI-A-1] Failed to read TMDS config: -6
<7> [347.744179] i915 0000:00:02.0: [drm:intel_ddi_enable [i915]] [CONNECTOR:587:HDMI-A-1] Failed to configure sink scrambling/TMDS bit clock ratio
<7> [347.747919] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [347.748101] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:34:plane 1A]
<7> [347.752834] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:587:HDMI-A-1][ENCODER:586:DDI B/PHY B] Enable audio codec on [CRTC:170:pipe A], 64 bytes ELD
<7> [347.761208] 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> [347.761399] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)
<7> [347.761560] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<6> [347.761725] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 5 not expected
<6> [347.761727] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 0 not expected
<6> [347.761729] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 10 not expected
<6> [347.761731] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 6 not expected
<7> [347.761870] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:587:HDMI-A-1]
<7> [347.762126] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:170:pipe A]
<7> [347.818798] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [347.823224] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [347.823268] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<7> [348.618519] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port G/TC#4: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [348.618536] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port F/TC#3: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [348.618572] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port E/TC#2: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [348.618595] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling TC_cold_off
<7> [348.619233] i915 0000:00:02.0: [drm:tgl_tc_cold_request [i915]] TC cold unblock succeeded
<7> [348.620853] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port D/TC#1: TC port mode reset (legacy -> disconnected) pin assignment: - max lanes: 4
<7> [358.230177] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:170:pipe A]
<7> [358.230561] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [358.231139] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [358.231737] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [358.232261] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [358.232826] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [358.233395] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [358.233881] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [358.234053] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2080, found 0)
<7> [358.234211] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [358.234379] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2080, found 0)
<7> [358.234550] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1968, found 0)
<7> [358.234708] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2000, found 0)
<7> [358.234866] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [358.235025] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [358.235181] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [358.235348] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [358.235519] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1144, found 0)
<7> [358.235677] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1144, found 0)
<7> [358.235832] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [358.235989] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2080, found 0)
<7> [358.236144] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [358.236300] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2080, found 0)
<7> [358.236476] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1968, found 0)
<7> [358.236648] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2000, found 0)
<7> [358.236805] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [358.236961] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [358.237118] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [358.237275] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [358.237450] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1144, found 0)
<7> [358.237621] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1144, found 0)
<7> [358.237779] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [358.237937] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [358.238094] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [358.238251] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [358.238426] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [358.238604] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [358.238764] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 285500, found 0)
<7> [358.238924] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 285500, found 0)
<7> [358.239082] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in port_clock (expected 285500, found 0)
<7> [358.239239] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [358.239419] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in avi infoframe
<7> [358.239589] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [358.239746] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [358.239748] i915 0000:00:02.0: colorspace: RGB
<7> [358.239750] i915 0000:00:02.0: scan mode: Underscan
<7> [358.239751] i915 0000:00:02.0: colorimetry: No Data
<7> [358.239753] i915 0000:00:02.0: picture aspect: No Data
<7> [358.239754] i915 0000:00:02.0: active aspect: Same as Picture
<7> [358.239756] i915 0000:00:02.0: itc: No Data
<7> [358.239757] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [358.239758] i915 0000:00:02.0: quantization range: Full
<7> [358.239760] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [358.239761] i915 0000:00:02.0: video code: 0
<7> [358.239763] i915 0000:00:02.0: ycc quantization range: Full
<7> [358.239764] i915 0000:00:02.0: hdmi content type: Graphics
<7> [358.239766] i915 0000:00:02.0: pixel repeat: 0
<7> [358.239767] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [358.239769] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [358.239926] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in spd infoframe
<7> [358.240084] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [358.240241] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [358.240242] i915 0000:00:02.0: vendor: Intel
<7> [358.240244] i915 0000:00:02.0: product: Integrated gfx
<7> [358.240246] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [358.240247] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [358.240425] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hdmi infoframe
<7> [358.240605] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [358.240760] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [358.240762] i915 0000:00:02.0: empty frame
<7> [358.240764] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [358.240921] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] fastset requirement not met, forcing full modeset
<7> [358.241079] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:170:pipe A] releasing DPLL 0
<7> [358.241285] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [358.241456] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:170:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [358.241650] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 1993) -> ( 0 - 0), size 1993 -> 0
<7> [358.241807] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:164:cursor A] ddb (1993 - 2048) -> ( 0 - 0), size 55 -> 0
<7> [358.241962] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34: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> [358.242115] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] lines 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [358.242282] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] blocks 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [358.242448] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] 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> [358.242621] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:170:pipe A] data rate 0 num active planes 0
<7> [358.242783] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 0
<7> [358.242963] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:170:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [358.243145] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 17844 kHz -> 0 kHz
<7> [358.243365] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [358.243526] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz
<7> [358.243683] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [358.243843] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:170:pipe A] enable: no [modeset]
<7> [358.244006] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:plane 1A] fb: [NOFB], visible: no
<7> [358.244165] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [358.244343] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [358.244513] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [358.244671] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [358.244829] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:plane 6A] fb: [NOFB], visible: no
<7> [358.244988] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:154:plane 7A] fb: [NOFB], visible: no
<7> [358.245145] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:164:cursor A] fb: [NOFB], visible: no
<7> [358.245409] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:587:HDMI-A-1][ENCODER:586:DDI B/PHY B] Disable audio codec on [CRTC:170:pipe A]
<7> [358.254406] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B
<7> [358.264775] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:587:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [358.265360] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [358.265569] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [358.266169] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [358.266337] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:587:HDMI-A-1] Failed to read TMDS config: -6
<7> [358.266352] i915 0000:00:02.0: [drm:intel_ddi_disable [i915]] [CONNECTOR:587:HDMI-A-1] Failed to reset sink scrambling/TMDS bit clock ratio
<7> [358.266542] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [358.271883] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [358.272103] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:170:pipe A]
<7> [358.272313] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [358.272507] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:34:plane 1A]
<7> [358.272697] 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> [358.273010] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:586:DDI B/PHY B]
<7> [358.273318] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:596:DDI TC1/PHY TC1]
<7> [358.273584] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:598:DP-MST A]
<7> [358.273979] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST B]
<7> [358.274172] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST C]
<7> [358.274353] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST D]
<7> [358.274529] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI TC2/PHY TC2]
<7> [358.274759] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:608:DP-MST A]
<7> [358.275209] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST B]
<7> [358.275488] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST C]
<7> [358.275762] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST D]
<7> [358.276077] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DDI TC3/PHY TC3]
<7> [358.276268] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:617:DP-MST A]
<7> [358.276457] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST B]
<7> [358.276666] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST C]
<7> [358.276969] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST D]
<7> [358.277201] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DDI TC4/PHY TC4]
<7> [358.277434] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST A]
<7> [358.277657] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST B]
<7> [358.277838] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST C]
<7> [358.277996] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST D]
<7> [358.278153] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:587:HDMI-A-1]
<7> [358.278593] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [358.278953] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [358.279222] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [358.279418] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:170:pipe A]
<7> [358.280114] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:308:pipe B]
<7> [358.280407] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:446:pipe C]
<7> [358.280652] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:584:pipe D]
<7> [358.280881] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:170:pipe A]
<7> [358.280933] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:587:HDMI-A-1]
<7> [358.281043] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:587:HDMI-A-1] Limiting target display pipe bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [358.281234] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [358.281462] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [358.281703] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:586:DDI B/PHY B] [CRTC:170:pipe A]
<7> [358.281965] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [358.282207] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [358.282387] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [358.282547] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [358.282720] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [358.282902] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [358.283139] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [358.283379] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2080)
<7> [358.283617] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [358.283864] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2080)
<7> [358.284059] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1968)
<7> [358.284218] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2000)
<7> [358.284375] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [358.284532] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [358.284878] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [358.285115] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [358.285354] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1144)
<7> [358.285587] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1144)
<7> [358.285788] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [358.285948] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2080)
<7> [358.286106] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [358.286266] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2080)
<7> [358.286500] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1968)
<7> [358.286749] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2000)
<7> [358.286985] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [358.287221] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [358.287438] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [358.287600] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [358.287779] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1144)
<7> [358.287937] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1144)
<7> [358.288094] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [358.288332] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [358.288571] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [358.288854] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [358.289086] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [358.289252] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [358.289411] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 285500)
<7> [358.289573] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 285500)
<7> [358.289803] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in port_clock (expected 0, found 285500)
<7> [358.290042] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [358.290279] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in avi infoframe
<7> [358.290515] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [358.290764] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [358.290943] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [358.290946] i915 0000:00:02.0: colorspace: RGB
<7> [358.290947] i915 0000:00:02.0: scan mode: Underscan
<7> [358.290949] i915 0000:00:02.0: colorimetry: No Data
<7> [358.290950] i915 0000:00:02.0: picture aspect: No Data
<7> [358.290952] i915 0000:00:02.0: active aspect: Same as Picture
<7> [358.290953] i915 0000:00:02.0: itc: No Data
<7> [358.290955] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [358.290956] i915 0000:00:02.0: quantization range: Full
<7> [358.290958] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [358.290959] i915 0000:00:02.0: video code: 0
<7> [358.290961] i915 0000:00:02.0: ycc quantization range: Full
<7> [358.290962] i915 0000:00:02.0: hdmi content type: Graphics
<7> [358.290963] i915 0000:00:02.0: pixel repeat: 0
<7> [358.290965] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [358.290967] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in spd infoframe
<7> [358.291125] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [358.291283] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [358.291441] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [358.291443] i915 0000:00:02.0: vendor: Intel
<7> [358.291445] i915 0000:00:02.0: product: Integrated gfx
<7> [358.291446] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [358.291448] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hdmi infoframe
<7> [358.291677] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [358.291915] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [358.292150] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [358.292153] i915 0000:00:02.0: empty frame
<7> [358.292157] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] fastset requirement not met, forcing full modeset
<7> [358.292452] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [358.292707] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:170:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x1
<7> [358.292947] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 0) -> ( 0 - 1993), size 0 -> 1993
<7> [358.293103] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:164:cursor A] ddb ( 0 - 0) -> (1993 - 2048), size 0 -> 55
<7> [358.293257] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34: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> [358.293415] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34: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> [358.293662] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34: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> [358.293898] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34: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> [358.294161] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:170:pipe A] data rate 1142000 num active planes 1
<7> [358.294396] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 1142
<7> [358.294596] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [358.294782] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:170:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [358.294962] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 17844 kHz
<7> [358.295164] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [358.295403] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [358.295659] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [358.295897] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:170:pipe A] allocated DPLL 0
<7> [358.296131] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:170:pipe A] reserving DPLL 0
<7> [358.296301] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:170:pipe A] enable: yes [modeset]
<7> [358.296463] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [358.296641] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [358.296850] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [358.297088] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [358.297328] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [358.297580] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [358.297781] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [358.297943] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x0
<7> [358.298104] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [358.298106] i915 0000:00:02.0: colorspace: RGB
<7> [358.298107] i915 0000:00:02.0: scan mode: Underscan
<7> [358.298109] i915 0000:00:02.0: colorimetry: No Data
<7> [358.298111] i915 0000:00:02.0: picture aspect: No Data
<7> [358.298112] i915 0000:00:02.0: active aspect: Same as Picture
<7> [358.298114] i915 0000:00:02.0: itc: No Data
<7> [358.298115] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [358.298117] i915 0000:00:02.0: quantization range: Full
<7> [358.298118] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [358.298120] i915 0000:00:02.0: video code: 0
<7> [358.298121] i915 0000:00:02.0: ycc quantization range: Full
<7> [358.298122] i915 0000:00:02.0: hdmi content type: Graphics
<7> [358.298124] i915 0000:00:02.0: pixel repeat: 0
<7> [358.298125] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [358.298127] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [358.298129] i915 0000:00:02.0: vendor: Intel
<7> [358.298130] i915 0000:00:02.0: product: Integrated gfx
<7> [358.298131] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [358.298133] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [358.298135] i915 0000:00:02.0: empty frame
<7> [358.298136] 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> [358.298297] 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> [358.298534] 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> [358.298769] 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> [358.299007] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [358.299242] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [358.299473] 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> [358.299675] 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> [358.299856] 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> [358.300015] 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> [358.300174] 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> [358.300331] 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> [358.300506] 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> [358.300739] 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> [358.300978] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 285500, pipe src: 1920x1080+0+0, pixel rate 285500, min cdclk 192000
<7> [358.301213] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [358.301441] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [358.301669] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [358.301844] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [358.302004] 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> [358.302165] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [358.302350] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [358.302606] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [358.302841] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [358.303075] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [358.303297] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [358.303466] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [358.303625] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [358.303783] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [358.303966] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [358.304203] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [358.304447] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [358.304691] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [358.304925] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:plane 1A] fb: [FB:635] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [358.305100] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [358.305260] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [358.305439] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [358.305603] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [358.305826] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [358.306067] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [358.306303] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:plane 6A] fb: [NOFB], visible: no
<7> [358.306556] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:154:plane 7A] fb: [NOFB], visible: no
<7> [358.306745] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:164:cursor A] fb: [NOFB], visible: no
<7> [358.307039] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [358.307213] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [358.307470] 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> [358.307802] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:586:DDI B/PHY B]
<7> [358.308041] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:596:DDI TC1/PHY TC1]
<7> [358.308245] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:598:DP-MST A]
<7> [358.308422] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST B]
<7> [358.308580] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST C]
<7> [358.308738] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST D]
<7> [358.308966] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI TC2/PHY TC2]
<7> [358.309200] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:608:DP-MST A]
<7> [358.309457] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST B]
<7> [358.309685] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST C]
<7> [358.309852] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST D]
<7> [358.310009] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DDI TC3/PHY TC3]
<7> [358.310173] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:617:DP-MST A]
<7> [358.310352] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST B]
<7> [358.310587] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST C]
<7> [358.310821] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST D]
<7> [358.311057] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DDI TC4/PHY TC4]
<7> [358.311283] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST A]
<7> [358.311524] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST B]
<7> [358.311695] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST C]
<7> [358.311850] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST D]
<7> [358.312134] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [358.312400] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:170:pipe A]
<7> [358.312481] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [358.312567] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [358.312802] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [358.313045] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [358.313388] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:587:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [358.313941] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [358.314096] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [358.314656] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [358.314879] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:587:HDMI-A-1] Failed to read TMDS config: -6
<7> [358.314905] i915 0000:00:02.0: [drm:intel_ddi_enable [i915]] [CONNECTOR:587:HDMI-A-1] Failed to configure sink scrambling/TMDS bit clock ratio
<7> [358.317291] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [358.317328] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<7> [358.318257] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [358.318524] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:34:plane 1A]
<7> [358.323616] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:587:HDMI-A-1][ENCODER:586:DDI B/PHY B] Enable audio codec on [CRTC:170:pipe A], 64 bytes ELD
<7> [358.331973] 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> [358.332205] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)
<7> [358.332365] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<6> [358.332531] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 5 not expected
<6> [358.332534] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 0 not expected
<6> [358.332535] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 10 not expected
<6> [358.332537] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 6 not expected
<7> [358.332671] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:587:HDMI-A-1]
<7> [358.332926] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:170:pipe A]
<6> [366.163559] [IGT] kms_flip: finished subtest A-HDMI-A1, FAIL
Created at 2026-03-17 17:47:03