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

Result: Fail

git-log-oneline i915_display_info18 igt_runner18 runtimes18 results18.json results18-i915-load.json guc_logs18.tar i915_display_info_post_exec18 boot18 dmesg18

DetailValue
Duration 18.64 seconds
Hostname 
shard-tglu-5
Igt-Version 
IGT-Version: 2.3-g3a4006cbc (x86_64) (Linux: 7.0.0-rc3-CI_DRM_18139-g3fb8dfc6a69e+ x86_64)
Out 
Using IGT_SRANDOM=1773441589 for randomisation
Opened device: /dev/dri/card0
Using monotonic timestamps
Starting subtest: wf_vblank-ts-check
Starting dynamic subtest: A-HDMI-A1
  1920x1080: 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5 
Expected frametime: 8335us; measured 8334.9us +- 3.803us accuracy 0.14%
Event vblank: expected 903, counted 890, passrate = 40.45%, encoder type 2
Expected frametime: 8335us; measured 8443.1us +- 3.981us accuracy 0.14%
vblank interval differs from modeline! expected 8334.6us, measured 8443us +- 3.981us, difference 108.6us (27.3 sigma)
Event vblank: expected 895, counted 900, passrate = 43.33%, 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.643s)
Err 
Starting subtest: wf_vblank-ts-check
Starting dynamic subtest: A-HDMI-A1
(kms_flip:2068) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1707:
(kms_flip:2068) CRITICAL: Failed assertion: !retried
Dynamic subtest A-HDMI-A1 failed.
**** DEBUG ****
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 58.019947/1975, current 58.104912/1985: elapsed=84965.0us expected=83349.4us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 58.104912
last_received_ts = 58.104813
last_seq = 1985
current_ts = 58.189873
current_received_ts = 58.189766
current_seq = 1995
count = 88
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 58.104912/1985, current 58.189873/1995: elapsed=84959.0us expected=83349.4us +- 416.7us, error 1.9%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 58.104912/1985, current 58.189873/1995: elapsed=84959.0us expected=83349.4us
(kms_flip:2068) INFO: Event vblank: expected 903, counted 890, passrate = 40.45%, encoder type 2
(kms_flip:2068) DEBUG: dropped frames, expected 903, counted 890, passrate = 40.45%, encoder type 2
(kms_flip:2068) DEBUG: Retrying without a hotplug event
(kms_flip:2068) DEBUG: No stale events found
(kms_flip:2068) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:2068) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:2068) INFO: Expected frametime: 8335us; measured 8443.1us +- 3.981us accuracy 0.14%
(kms_flip:2068) INFO: vblank interval differs from modeline! expected 8334.6us, measured 8443us +- 3.981us, difference 108.6us (27.3 sigma)
(kms_flip:2068) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 61.554287
current_received_ts = 61.554157
current_seq = 2394
count = 0
seq_step = 10
(kms_flip:2068) DEBUG: name = vblank
last_ts = 61.554287
last_received_ts = 61.554157
last_seq = 2394
current_ts = 61.638718
current_received_ts = 61.638657
current_seq = 2404
count = 1
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 61.554287/2394, current 61.638718/2404: elapsed=84428.0us expected=84431.2us +- 422.2us, error 0.0%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 61.638718
last_received_ts = 61.638657
last_seq = 2404
current_ts = 61.723133
current_received_ts = 61.723099
current_seq = 2414
count = 2
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 61.638718/2404, current 61.723133/2414: elapsed=84417.0us expected=84431.2us +- 422.2us, error 0.0%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 61.723133
last_received_ts = 61.723099
last_seq = 2414
current_ts = 61.807430
current_received_ts = 61.807358
current_seq = 2424
count = 3
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 61.723133/2414, current 61.807430/2424: elapsed=84297.0us expected=84431.2us +- 422.2us, error 0.2%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 61.807430
last_received_ts = 61.807358
last_seq = 2424
current_ts = 61.891724
current_received_ts = 61.891659
current_seq = 2434
count = 4
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 61.807430/2424, current 61.891724/2434: elapsed=84293.0us expected=84431.2us +- 422.2us, error 0.2%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 61.891724
last_received_ts = 61.891659
last_seq = 2434
current_ts = 61.976017
current_received_ts = 61.975967
current_seq = 2444
count = 5
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 61.891724/2434, current 61.976017/2444: elapsed=84295.0us expected=84431.2us +- 422.2us, error 0.2%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 61.976017
last_received_ts = 61.975967
last_seq = 2444
current_ts = 62.060314
current_received_ts = 62.060246
current_seq = 2454
count = 6
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 61.976017/2444, current 62.060314/2454: elapsed=84295.0us expected=84431.2us +- 422.2us, error 0.2%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 62.060314
last_received_ts = 62.060246
last_seq = 2454
current_ts = 62.144604
current_received_ts = 62.144539
current_seq = 2464
count = 7
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 62.060314/2454, current 62.144604/2464: elapsed=84292.0us expected=84431.2us +- 422.2us, error 0.2%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 62.144604
last_received_ts = 62.144539
last_seq = 2464
current_ts = 62.228901
current_received_ts = 62.228771
current_seq = 2474
count = 8
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 62.144604/2464, current 62.228901/2474: elapsed=84296.0us expected=84431.2us +- 422.2us, error 0.2%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 62.228901
last_received_ts = 62.228771
last_seq = 2474
current_ts = 62.313190
current_received_ts = 62.312817
current_seq = 2484
count = 9
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 62.228901/2474, current 62.313190/2484: elapsed=84289.0us expected=84431.2us +- 422.2us, error 0.2%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 62.313190
last_received_ts = 62.312817
last_seq = 2484
current_ts = 62.397484
current_received_ts = 62.397408
current_seq = 2494
count = 10
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 62.313190/2484, current 62.397484/2494: elapsed=84295.0us expected=84431.2us +- 422.2us, error 0.2%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 62.397484
last_received_ts = 62.397408
last_seq = 2494
current_ts = 62.481781
current_received_ts = 62.481720
current_seq = 2504
count = 11
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 62.397484/2494, current 62.481781/2504: elapsed=84295.0us expected=84431.2us +- 422.2us, error 0.2%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 62.481781
last_received_ts = 62.481720
last_seq = 2504
current_ts = 62.566074
current_received_ts = 62.566078
current_seq = 2514
count = 12
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 62.481781/2504, current 62.566074/2514: elapsed=84295.0us expected=84431.2us +- 422.2us, error 0.2%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 62.566074
last_received_ts = 62.566078
last_seq = 2514
current_ts = 62.650372
current_received_ts = 62.650314
current_seq = 2524
count = 13
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 62.566074/2514, current 62.650372/2524: elapsed=84296.0us expected=84431.2us +- 422.2us, error 0.2%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 62.650372
last_received_ts = 62.650314
last_seq = 2524
current_ts = 62.734638
current_received_ts = 62.734573
current_seq = 2534
count = 14
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 62.650372/2524, current 62.734638/2534: elapsed=84267.0us expected=84431.2us +- 422.2us, error 0.2%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 62.734638
last_received_ts = 62.734573
last_seq = 2534
current_ts = 62.818817
current_received_ts = 62.818771
current_seq = 2544
count = 15
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 62.734638/2534, current 62.818817/2544: elapsed=84179.0us expected=84431.2us +- 422.2us, error 0.3%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 62.818817
last_received_ts = 62.818771
last_seq = 2544
current_ts = 62.902988
current_received_ts = 62.902916
current_seq = 2554
count = 16
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 62.818817/2544, current 62.902988/2554: elapsed=84173.0us expected=84431.2us +- 422.2us, error 0.3%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 62.902988
last_received_ts = 62.902916
last_seq = 2554
current_ts = 62.987167
current_received_ts = 62.987053
current_seq = 2564
count = 17
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 62.902988/2554, current 62.987167/2564: elapsed=84178.0us expected=84431.2us +- 422.2us, error 0.3%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 62.987167
last_received_ts = 62.987053
last_seq = 2564
current_ts = 63.071339
current_received_ts = 63.071262
current_seq = 2574
count = 18
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 62.987167/2564, current 63.071339/2574: elapsed=84172.0us expected=84431.2us +- 422.2us, error 0.3%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 63.071339
last_received_ts = 63.071262
last_seq = 2574
current_ts = 63.155514
current_received_ts = 63.155293
current_seq = 2584
count = 19
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 63.071339/2574, current 63.155514/2584: elapsed=84175.0us expected=84431.2us +- 422.2us, error 0.3%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 63.155514
last_received_ts = 63.155293
last_seq = 2584
current_ts = 63.239693
current_received_ts = 63.239628
current_seq = 2594
count = 20
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 63.155514/2584, current 63.239693/2594: elapsed=84177.0us expected=84431.2us +- 422.2us, error 0.3%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 63.239693
last_received_ts = 63.239628
last_seq = 2594
current_ts = 63.323864
current_received_ts = 63.323746
current_seq = 2604
count = 21
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 63.239693/2594, current 63.323864/2604: elapsed=84173.0us expected=84431.2us +- 422.2us, error 0.3%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 63.323864
last_received_ts = 63.323746
last_seq = 2604
current_ts = 63.408043
current_received_ts = 63.407890
current_seq = 2614
count = 22
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 63.323864/2604, current 63.408043/2614: elapsed=84176.0us expected=84431.2us +- 422.2us, error 0.3%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 63.408043
last_received_ts = 63.407890
last_seq = 2614
current_ts = 63.492218
current_received_ts = 63.492168
current_seq = 2624
count = 23
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 63.408043/2614, current 63.492218/2624: elapsed=84177.0us expected=84431.2us +- 422.2us, error 0.3%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 63.492218
last_received_ts = 63.492168
last_seq = 2624
current_ts = 63.576393
current_received_ts = 63.576286
current_seq = 2634
count = 24
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 63.492218/2624, current 63.576393/2634: elapsed=84174.0us expected=84431.2us +- 422.2us, error 0.3%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 63.576393
last_received_ts = 63.576286
last_seq = 2634
current_ts = 63.660568
current_received_ts = 63.660503
current_seq = 2644
count = 25
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 63.576393/2634, current 63.660568/2644: elapsed=84175.0us expected=84431.2us +- 422.2us, error 0.3%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 63.660568
last_received_ts = 63.660503
last_seq = 2644
current_ts = 63.744713
current_received_ts = 63.744644
current_seq = 2654
count = 26
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 63.660568/2644, current 63.744713/2654: elapsed=84144.0us expected=84431.2us +- 422.2us, error 0.3%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 63.744713
last_received_ts = 63.744644
last_seq = 2654
current_ts = 63.828789
current_received_ts = 63.828735
current_seq = 2664
count = 27
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 63.744713/2654, current 63.828789/2664: elapsed=84077.0us expected=84431.2us +- 422.2us, error 0.4%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 63.828789
last_received_ts = 63.828735
last_seq = 2664
current_ts = 63.912857
current_received_ts = 63.912800
current_seq = 2674
count = 28
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 63.828789/2664, current 63.912857/2674: elapsed=84069.0us expected=84431.2us +- 422.2us, error 0.4%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 63.912857
last_received_ts = 63.912800
last_seq = 2674
current_ts = 63.996929
current_received_ts = 63.996853
current_seq = 2684
count = 29
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 63.912857/2674, current 63.996929/2684: elapsed=84071.0us expected=84431.2us +- 422.2us, error 0.4%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 63.996929
last_received_ts = 63.996853
last_seq = 2684
current_ts = 64.081001
current_received_ts = 64.080933
current_seq = 2694
count = 30
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 63.996929/2684, current 64.081001/2694: elapsed=84074.0us expected=84431.2us +- 422.2us, error 0.4%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 64.081001
last_received_ts = 64.080933
last_seq = 2694
current_ts = 64.165070
current_received_ts = 64.165062
current_seq = 2704
count = 31
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 64.081001/2694, current 64.165070/2704: elapsed=84067.0us expected=84431.2us +- 422.2us, error 0.4%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 64.165070
last_received_ts = 64.165062
last_seq = 2704
current_ts = 64.249138
current_received_ts = 64.248795
current_seq = 2714
count = 32
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 64.165070/2704, current 64.249138/2714: elapsed=84070.0us expected=84431.2us +- 422.2us, error 0.4%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 64.249138
last_received_ts = 64.248795
last_seq = 2714
current_ts = 64.333214
current_received_ts = 64.333183
current_seq = 2724
count = 33
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 64.249138/2714, current 64.333214/2724: elapsed=84076.0us expected=84431.2us +- 422.2us, error 0.4%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 64.333214
last_received_ts = 64.333183
last_seq = 2724
current_ts = 64.417290
current_received_ts = 64.417198
current_seq = 2734
count = 34
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 64.333214/2724, current 64.417290/2734: elapsed=84074.0us expected=84431.2us +- 422.2us, error 0.4%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 64.417290
last_received_ts = 64.417198
last_seq = 2734
current_ts = 64.501358
current_received_ts = 64.501289
current_seq = 2744
count = 35
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 64.417290/2734, current 64.501358/2744: elapsed=84068.0us expected=84431.2us +- 422.2us, error 0.4%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 64.501358
last_received_ts = 64.501289
last_seq = 2744
current_ts = 64.585434
current_received_ts = 64.585381
current_seq = 2754
count = 36
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 64.501358/2744, current 64.585434/2754: elapsed=84074.0us expected=84431.2us +- 422.2us, error 0.4%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 64.585434
last_received_ts = 64.585381
last_seq = 2754
current_ts = 64.669502
current_received_ts = 64.669456
current_seq = 2764
count = 37
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 64.585434/2754, current 64.669502/2764: elapsed=84074.0us expected=84431.2us +- 422.2us, error 0.4%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 64.669502
last_received_ts = 64.669456
last_seq = 2764
current_ts = 64.753532
current_received_ts = 64.753471
current_seq = 2774
count = 38
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 64.669502/2764, current 64.753532/2774: elapsed=84031.0us expected=84431.2us +- 422.2us, error 0.5%
(kms_flip:2068) DEBUG: name = vblank
last_ts = 64.753532
last_received_ts = 64.753471
last_seq = 2774
current_ts = 64.837517
current_received_ts = 64.837463
current_seq = 2784
count = 39
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 64.753532/2774, current 64.837517/2784: elapsed=83979.0us expected=84431.2us +- 422.2us, error 0.5%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 64.753532/2774, current 64.837517/2784: elapsed=83979.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 64.837517
last_received_ts = 64.837463
last_seq = 2784
current_ts = 64.921494
current_received_ts = 64.921310
current_seq = 2794
count = 40
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 64.837517/2784, current 64.921494/2794: elapsed=83981.0us expected=84431.2us +- 422.2us, error 0.5%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 64.837517/2784, current 64.921494/2794: elapsed=83981.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 64.921494
last_received_ts = 64.921310
last_seq = 2794
current_ts = 65.005478
current_received_ts = 65.005417
current_seq = 2804
count = 41
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 64.921494/2794, current 65.005478/2804: elapsed=83985.0us expected=84431.2us +- 422.2us, error 0.5%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 64.921494/2794, current 65.005478/2804: elapsed=83985.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 65.005478
last_received_ts = 65.005417
last_seq = 2804
current_ts = 65.089462
current_received_ts = 65.089409
current_seq = 2814
count = 42
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 65.005478/2804, current 65.089462/2814: elapsed=83982.0us expected=84431.2us +- 422.2us, error 0.5%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 65.005478/2804, current 65.089462/2814: elapsed=83982.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 65.089462
last_received_ts = 65.089409
last_seq = 2814
current_ts = 65.173447
current_received_ts = 65.173386
current_seq = 2824
count = 43
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 65.089462/2814, current 65.173447/2824: elapsed=83981.0us expected=84431.2us +- 422.2us, error 0.5%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 65.089462/2814, current 65.173447/2824: elapsed=83981.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 65.173447
last_received_ts = 65.173386
last_seq = 2824
current_ts = 65.257423
current_received_ts = 65.257370
current_seq = 2834
count = 44
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 65.173447/2824, current 65.257423/2834: elapsed=83977.0us expected=84431.2us +- 422.2us, error 0.5%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 65.173447/2824, current 65.257423/2834: elapsed=83977.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 65.257423
last_received_ts = 65.257370
last_seq = 2834
current_ts = 65.341408
current_received_ts = 65.341339
current_seq = 2844
count = 45
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 65.257423/2834, current 65.341408/2844: elapsed=83984.0us expected=84431.2us +- 422.2us, error 0.5%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 65.257423/2834, current 65.341408/2844: elapsed=83984.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 65.341408
last_received_ts = 65.341339
last_seq = 2844
current_ts = 65.425385
current_received_ts = 65.425323
current_seq = 2854
count = 46
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 65.341408/2844, current 65.425385/2854: elapsed=83978.0us expected=84431.2us +- 422.2us, error 0.5%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 65.341408/2844, current 65.425385/2854: elapsed=83978.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 65.425385
last_received_ts = 65.425323
last_seq = 2854
current_ts = 65.509361
current_received_ts = 65.509315
current_seq = 2864
count = 47
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 65.425385/2854, current 65.509361/2864: elapsed=83982.0us expected=84431.2us +- 422.2us, error 0.5%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 65.425385/2854, current 65.509361/2864: elapsed=83982.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 65.509361
last_received_ts = 65.509315
last_seq = 2864
current_ts = 65.593346
current_received_ts = 65.593300
current_seq = 2874
count = 48
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 65.509361/2864, current 65.593346/2874: elapsed=83981.0us expected=84431.2us +- 422.2us, error 0.5%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 65.509361/2864, current 65.593346/2874: elapsed=83981.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 65.593346
last_received_ts = 65.593300
last_seq = 2874
current_ts = 65.677330
current_received_ts = 65.677261
current_seq = 2884
count = 49
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 65.593346/2874, current 65.677330/2884: elapsed=83984.0us expected=84431.2us +- 422.2us, error 0.5%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 65.593346/2874, current 65.677330/2884: elapsed=83984.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 65.677330
last_received_ts = 65.677261
last_seq = 2884
current_ts = 65.761269
current_received_ts = 65.761101
current_seq = 2894
count = 50
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 65.677330/2884, current 65.761269/2894: elapsed=83936.0us expected=84431.2us +- 422.2us, error 0.6%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 65.677330/2884, current 65.761269/2894: elapsed=83936.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 65.761269
last_received_ts = 65.761101
last_seq = 2894
current_ts = 65.845169
current_received_ts = 65.844872
current_seq = 2904
count = 51
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 65.761269/2894, current 65.845169/2904: elapsed=83906.0us expected=84431.2us +- 422.2us, error 0.6%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 65.761269/2894, current 65.845169/2904: elapsed=83906.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 65.845169
last_received_ts = 65.844872
last_seq = 2904
current_ts = 65.929070
current_received_ts = 65.928993
current_seq = 2914
count = 52
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 65.845169/2904, current 65.929070/2914: elapsed=83899.0us expected=84431.2us +- 422.2us, error 0.6%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 65.845169/2904, current 65.929070/2914: elapsed=83899.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 65.929070
last_received_ts = 65.928993
last_seq = 2914
current_ts = 66.012978
current_received_ts = 66.012901
current_seq = 2924
count = 53
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 65.929070/2914, current 66.012978/2924: elapsed=83904.0us expected=84431.2us +- 422.2us, error 0.6%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 65.929070/2914, current 66.012978/2924: elapsed=83904.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 66.012978
last_received_ts = 66.012901
last_seq = 2924
current_ts = 66.096878
current_received_ts = 66.096809
current_seq = 2934
count = 54
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 66.012978/2924, current 66.096878/2934: elapsed=83902.0us expected=84431.2us +- 422.2us, error 0.6%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 66.012978/2924, current 66.096878/2934: elapsed=83902.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 66.096878
last_received_ts = 66.096809
last_seq = 2934
current_ts = 66.180779
current_received_ts = 66.180710
current_seq = 2944
count = 55
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 66.096878/2934, current 66.180779/2944: elapsed=83900.0us expected=84431.2us +- 422.2us, error 0.6%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 66.096878/2934, current 66.180779/2944: elapsed=83900.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 66.180779
last_received_ts = 66.180710
last_seq = 2944
current_ts = 66.264679
current_received_ts = 66.264458
current_seq = 2954
count = 56
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 66.180779/2944, current 66.264679/2954: elapsed=83901.0us expected=84431.2us +- 422.2us, error 0.6%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 66.180779/2944, current 66.264679/2954: elapsed=83901.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 66.264679
last_received_ts = 66.264458
last_seq = 2954
current_ts = 66.348579
current_received_ts = 66.348511
current_seq = 2964
count = 57
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 66.264679/2954, current 66.348579/2964: elapsed=83903.0us expected=84431.2us +- 422.2us, error 0.6%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 66.264679/2954, current 66.348579/2964: elapsed=83903.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 66.348579
last_received_ts = 66.348511
last_seq = 2964
current_ts = 66.432480
current_received_ts = 66.432411
current_seq = 2974
count = 58
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 66.348579/2964, current 66.432480/2974: elapsed=83902.0us expected=84431.2us +- 422.2us, error 0.6%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 66.348579/2964, current 66.432480/2974: elapsed=83902.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 66.432480
last_received_ts = 66.432411
last_seq = 2974
current_ts = 66.516380
current_received_ts = 66.516319
current_seq = 2984
count = 59
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 66.432480/2974, current 66.516380/2984: elapsed=83900.0us expected=84431.2us +- 422.2us, error 0.6%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 66.432480/2974, current 66.516380/2984: elapsed=83900.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 66.516380
last_received_ts = 66.516319
last_seq = 2984
current_ts = 66.600288
current_received_ts = 66.600212
current_seq = 2994
count = 60
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 66.516380/2984, current 66.600288/2994: elapsed=83903.0us expected=84431.2us +- 422.2us, error 0.6%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 66.516380/2984, current 66.600288/2994: elapsed=83903.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 66.600288
last_received_ts = 66.600212
last_seq = 2994
current_ts = 66.684189
current_received_ts = 66.683838
current_seq = 3004
count = 61
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 66.600288/2994, current 66.684189/3004: elapsed=83899.0us expected=84431.2us +- 422.2us, error 0.6%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 66.600288/2994, current 66.684189/3004: elapsed=83899.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 66.684189
last_received_ts = 66.683838
last_seq = 3004
current_ts = 66.768044
current_received_ts = 66.767662
current_seq = 3014
count = 62
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 66.684189/3004, current 66.768044/3014: elapsed=83855.0us expected=84431.2us +- 422.2us, error 0.7%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 66.684189/3004, current 66.768044/3014: elapsed=83855.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 66.768044
last_received_ts = 66.767662
last_seq = 3014
current_ts = 66.851875
current_received_ts = 66.851517
current_seq = 3024
count = 63
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 66.768044/3014, current 66.851875/3024: elapsed=83832.0us expected=84431.2us +- 422.2us, error 0.7%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 66.768044/3014, current 66.851875/3024: elapsed=83832.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 66.851875
last_received_ts = 66.851517
last_seq = 3024
current_ts = 66.935699
current_received_ts = 66.935272
current_seq = 3034
count = 64
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 66.851875/3024, current 66.935699/3034: elapsed=83831.0us expected=84431.2us +- 422.2us, error 0.7%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 66.851875/3024, current 66.935699/3034: elapsed=83831.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 66.935699
last_received_ts = 66.935272
last_seq = 3034
current_ts = 67.019539
current_received_ts = 67.019180
current_seq = 3044
count = 65
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 66.935699/3034, current 67.019539/3044: elapsed=83833.0us expected=84431.2us +- 422.2us, error 0.7%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 66.935699/3034, current 67.019539/3044: elapsed=83833.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 67.019539
last_received_ts = 67.019180
last_seq = 3044
current_ts = 67.103371
current_received_ts = 67.103020
current_seq = 3054
count = 66
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 67.019539/3044, current 67.103371/3054: elapsed=83834.0us expected=84431.2us +- 422.2us, error 0.7%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 67.019539/3044, current 67.103371/3054: elapsed=83834.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 67.103371
last_received_ts = 67.103020
last_seq = 3054
current_ts = 67.187202
current_received_ts = 67.186844
current_seq = 3064
count = 67
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 67.103371/3054, current 67.187202/3064: elapsed=83831.0us expected=84431.2us +- 422.2us, error 0.7%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 67.103371/3054, current 67.187202/3064: elapsed=83831.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 67.187202
last_received_ts = 67.186844
last_seq = 3064
current_ts = 67.271034
current_received_ts = 67.270683
current_seq = 3074
count = 68
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 67.187202/3064, current 67.271034/3074: elapsed=83833.0us expected=84431.2us +- 422.2us, error 0.7%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 67.187202/3064, current 67.271034/3074: elapsed=83833.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 67.271034
last_received_ts = 67.270683
last_seq = 3074
current_ts = 67.354866
current_received_ts = 67.354515
current_seq = 3084
count = 69
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 67.271034/3074, current 67.354866/3084: elapsed=83832.0us expected=84431.2us +- 422.2us, error 0.7%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 67.271034/3074, current 67.354866/3084: elapsed=83832.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 67.354866
last_received_ts = 67.354515
last_seq = 3084
current_ts = 67.438698
current_received_ts = 67.438324
current_seq = 3094
count = 70
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 67.354866/3084, current 67.438698/3094: elapsed=83831.0us expected=84431.2us +- 422.2us, error 0.7%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 67.354866/3084, current 67.438698/3094: elapsed=83831.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 67.438698
last_received_ts = 67.438324
last_seq = 3094
current_ts = 67.522530
current_received_ts = 67.522179
current_seq = 3104
count = 71
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 67.438698/3094, current 67.522530/3104: elapsed=83835.0us expected=84431.2us +- 422.2us, error 0.7%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 67.438698/3094, current 67.522530/3104: elapsed=83835.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 67.522530
last_received_ts = 67.522179
last_seq = 3104
current_ts = 67.606369
current_received_ts = 67.606285
current_seq = 3114
count = 72
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 67.522530/3104, current 67.606369/3114: elapsed=83834.0us expected=84431.2us +- 422.2us, error 0.7%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 67.522530/3104, current 67.606369/3114: elapsed=83834.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 67.606369
last_received_ts = 67.606285
last_seq = 3114
current_ts = 67.690193
current_received_ts = 67.690125
current_seq = 3124
count = 73
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 67.606369/3114, current 67.690193/3124: elapsed=83829.0us expected=84431.2us +- 422.2us, error 0.7%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 67.606369/3114, current 67.690193/3124: elapsed=83829.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 67.690193
last_received_ts = 67.690125
last_seq = 3124
current_ts = 67.773987
current_received_ts = 67.773911
current_seq = 3134
count = 74
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 67.690193/3124, current 67.773987/3134: elapsed=83792.0us expected=84431.2us +- 422.2us, error 0.8%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 67.690193/3124, current 67.773987/3134: elapsed=83792.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 67.773987
last_received_ts = 67.773911
last_seq = 3134
current_ts = 67.857758
current_received_ts = 67.857681
current_seq = 3144
count = 75
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 67.773987/3134, current 67.857758/3144: elapsed=83772.0us expected=84431.2us +- 422.2us, error 0.8%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 67.773987/3134, current 67.857758/3144: elapsed=83772.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 67.857758
last_received_ts = 67.857681
last_seq = 3144
current_ts = 67.941528
current_received_ts = 67.941460
current_seq = 3154
count = 76
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 67.857758/3144, current 67.941528/3154: elapsed=83769.0us expected=84431.2us +- 422.2us, error 0.8%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 67.857758/3144, current 67.941528/3154: elapsed=83769.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 67.941528
last_received_ts = 67.941460
last_seq = 3154
current_ts = 68.025299
current_received_ts = 68.025230
current_seq = 3164
count = 77
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 67.941528/3154, current 68.025299/3164: elapsed=83770.0us expected=84431.2us +- 422.2us, error 0.8%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 67.941528/3154, current 68.025299/3164: elapsed=83770.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 68.025299
last_received_ts = 68.025230
last_seq = 3164
current_ts = 68.109070
current_received_ts = 68.108925
current_seq = 3174
count = 78
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 68.025299/3164, current 68.109070/3174: elapsed=83775.0us expected=84431.2us +- 422.2us, error 0.8%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 68.025299/3164, current 68.109070/3174: elapsed=83775.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 68.109070
last_received_ts = 68.108925
last_seq = 3174
current_ts = 68.192848
current_received_ts = 68.192757
current_seq = 3184
count = 79
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 68.109070/3174, current 68.192848/3184: elapsed=83772.0us expected=84431.2us +- 422.2us, error 0.8%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 68.109070/3174, current 68.192848/3184: elapsed=83772.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 68.192848
last_received_ts = 68.192757
last_seq = 3184
current_ts = 68.276611
current_received_ts = 68.276543
current_seq = 3194
count = 80
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 68.192848/3184, current 68.276611/3194: elapsed=83766.0us expected=84431.2us +- 422.2us, error 0.8%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 68.192848/3184, current 68.276611/3194: elapsed=83766.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 68.276611
last_received_ts = 68.276543
last_seq = 3194
current_ts = 68.360382
current_received_ts = 68.360191
current_seq = 3204
count = 81
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 68.276611/3194, current 68.360382/3204: elapsed=83772.0us expected=84431.2us +- 422.2us, error 0.8%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 68.276611/3194, current 68.360382/3204: elapsed=83772.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 68.360382
last_received_ts = 68.360191
last_seq = 3204
current_ts = 68.444153
current_received_ts = 68.444099
current_seq = 3214
count = 82
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 68.360382/3204, current 68.444153/3214: elapsed=83772.0us expected=84431.2us +- 422.2us, error 0.8%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 68.360382/3204, current 68.444153/3214: elapsed=83772.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 68.444153
last_received_ts = 68.444099
last_seq = 3214
current_ts = 68.527931
current_received_ts = 68.527863
current_seq = 3224
count = 83
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 68.444153/3214, current 68.527931/3224: elapsed=83773.0us expected=84431.2us +- 422.2us, error 0.8%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 68.444153/3214, current 68.527931/3224: elapsed=83773.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 68.527931
last_received_ts = 68.527863
last_seq = 3224
current_ts = 68.611694
current_received_ts = 68.611633
current_seq = 3234
count = 84
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 68.527931/3224, current 68.611694/3234: elapsed=83770.0us expected=84431.2us +- 422.2us, error 0.8%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 68.527931/3224, current 68.611694/3234: elapsed=83770.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 68.611694
last_received_ts = 68.611633
last_seq = 3234
current_ts = 68.695473
current_received_ts = 68.695267
current_seq = 3244
count = 85
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 68.611694/3234, current 68.695473/3244: elapsed=83775.0us expected=84431.2us +- 422.2us, error 0.8%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 68.611694/3234, current 68.695473/3244: elapsed=83775.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 68.695473
last_received_ts = 68.695267
last_seq = 3244
current_ts = 68.779205
current_received_ts = 68.779137
current_seq = 3254
count = 86
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 68.695473/3244, current 68.779205/3254: elapsed=83731.0us expected=84431.2us +- 422.2us, error 0.8%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 68.695473/3244, current 68.779205/3254: elapsed=83731.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 68.779205
last_received_ts = 68.779137
last_seq = 3254
current_ts = 68.862923
current_received_ts = 68.862862
current_seq = 3264
count = 87
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 68.779205/3254, current 68.862923/3264: elapsed=83719.0us expected=84431.2us +- 422.2us, error 0.8%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 68.779205/3254, current 68.862923/3264: elapsed=83719.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 68.862923
last_received_ts = 68.862862
last_seq = 3264
current_ts = 68.946640
current_received_ts = 68.946571
current_seq = 3274
count = 88
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 68.862923/3264, current 68.946640/3274: elapsed=83715.0us expected=84431.2us +- 422.2us, error 0.8%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 68.862923/3264, current 68.946640/3274: elapsed=83715.0us expected=84431.2us
(kms_flip:2068) DEBUG: name = vblank
last_ts = 68.946640
last_received_ts = 68.946571
last_seq = 3274
current_ts = 69.030357
current_received_ts = 69.030281
current_seq = 3284
count = 89
seq_step = 10
(kms_flip:2068) DEBUG: vblank ts/seq: last 68.946640/3274, current 69.030357/3284: elapsed=83720.0us expected=84431.2us +- 422.2us, error 0.8%
(kms_flip:2068) DEBUG: inconsistent vblank ts/seq: last 68.946640/3274, current 69.030357/3284: elapsed=83720.0us expected=84431.2us
(kms_flip:2068) INFO: Event vblank: expected 895, counted 900, passrate = 43.33%, encoder type 2
(kms_flip:2068) DEBUG: dropped frames, expected 895, counted 900, passrate = 43.33%, encoder type 2
(kms_flip:2068) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1707:
(kms_flip:2068) CRITICAL: Failed assertion: !retried
(kms_flip:2068) igt_core-INFO: Stack trace:
(kms_flip:2068) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2068) igt_core-INFO:   #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
(kms_flip:2068) igt_core-INFO:   #2 ../tests/kms_flip.c:1887 run_test()
(kms_flip:2068) igt_core-INFO:   #3 ../tests/kms_flip.c:2122 __igt_unique____real_main2043()
(kms_flip:2068) igt_core-INFO:   #4 ../tests/kms_flip.c:2043 main()
(kms_flip:2068) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2068) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2068) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-HDMI-A1: FAIL (18.643s)
Dmesg
<6> [50.301181] Console: switching to colour dummy device 80x25
<6> [50.301267] [IGT] kms_flip: executing
<7> [50.303700] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [50.304393] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [50.304673] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [50.314388] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:597:DP-1]
<7> [50.314396] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:597:DP-1]
<7> [50.318792] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:597:DP-1] disconnected
<7> [50.319146] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:DP-2]
<7> [50.319150] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:607:DP-2]
<7> [50.319520] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:DP-2] disconnected
<7> [50.319890] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:616:DP-3]
<7> [50.319893] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:616:DP-3]
<7> [50.320232] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:616:DP-3] disconnected
<7> [50.320588] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:625:DP-4]
<7> [50.320592] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:625:DP-4]
<7> [50.320925] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:625:DP-4] disconnected
<7> [50.321479] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:587:HDMI-A-1]
<7> [50.321490] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:587:HDMI-A-1]
<7> [50.348663] 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> [50.348673] 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> [50.348676] 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> [50.348679] 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> [50.348682] 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> [50.348685] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] ELD monitor fitHeadlessGS
<7> [50.348688] 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> [50.348691] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] ELD size 64, SAD count 10
<7> [50.349098] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0040 w(1)
<7> [50.349319] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [50.349854] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0040 w(1)
<7> [50.350061] i915 0000:00:02.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [50.350874] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:587:HDMI-A-1] probed modes:
<7> [50.350878] 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> [50.350881] 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> [50.350884] 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> [50.350887] 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> [50.350890] 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> [50.350893] 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> [50.350896] 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> [50.350899] 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> [50.350902] 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> [50.350905] 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> [50.350908] 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> [50.350911] 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> [50.350914] 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> [50.350916] 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> [50.350919] 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> [50.350922] 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> [50.350925] 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> [50.350928] 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> [50.350931] 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> [50.350934] 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> [50.350937] 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> [50.350940] 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> [50.350942] 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> [50.350945] 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> [50.350948] 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> [50.350951] 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> [50.350954] 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> [50.350957] 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> [50.350960] 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> [50.350963] 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> [50.350966] 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> [50.350969] 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> [50.350972] 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> [50.350975] 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> [50.350977] 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> [50.350980] 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> [50.350983] 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> [50.350986] 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> [50.350989] 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> [50.350992] 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> [50.350995] 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> [50.350998] 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> [50.351001] 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> [50.351004] 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> [50.351007] 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> [50.351010] 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> [50.351013] 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> [50.351016] 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> [50.351018] 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> [50.351021] 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> [50.351024] 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> [50.351027] 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> [50.351030] 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> [50.351033] 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> [50.351036] 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> [50.351039] 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> [50.351042] 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> [50.351045] 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> [50.351048] 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> [50.351050] 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> [50.351053] 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> [50.351056] 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> [50.351059] 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> [50.351062] 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> [50.351065] 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> [50.351068] 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> [50.351071] 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> [50.351073] 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> [50.351076] 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> [50.351079] 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> [50.351082] 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> [50.351085] 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> [50.351088] 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> [50.351124] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:597:DP-1]
<7> [50.351127] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:597:DP-1]
<7> [50.355544] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:597:DP-1] disconnected
<7> [50.355559] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:DP-2]
<7> [50.355562] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:607:DP-2]
<7> [50.355901] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:DP-2] disconnected
<7> [50.355914] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:616:DP-3]
<7> [50.355917] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:616:DP-3]
<7> [50.356243] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:616:DP-3] disconnected
<7> [50.356255] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:625:DP-4]
<7> [50.356258] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:625:DP-4]
<7> [50.356595] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:625:DP-4] disconnected
<6> [50.357018] [IGT] kms_flip: starting subtest wf_vblank-ts-check
<6> [50.359486] [IGT] kms_flip: starting dynamic subtest A-HDMI-A1
<7> [50.359928] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:633]
<7> [50.360018] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:637]
<7> [50.423766] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:170:pipe A]
<7> [50.423933] 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> [50.424246] 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> [50.424515] 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> [50.424777] 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> [50.425030] 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> [50.425308] 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> [50.425571] 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> [50.425833] 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> [50.426095] 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> [50.426371] 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> [50.426632] 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> [50.426891] 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> [50.427149] 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> [50.427435] 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> [50.427695] 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> [50.427952] 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> [50.428220] 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> [50.428479] 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> [50.428685] 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> [50.428887] 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> [50.429087] 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> [50.429305] 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> [50.429503] 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> [50.429702] 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> [50.429903] 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> [50.430103] 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> [50.430315] 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> [50.430514] 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> [50.430713] 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> [50.430911] 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> [50.431110] 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> [50.431320] 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> [50.431486] 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> [50.431643] 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> [50.431798] 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> [50.431954] 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> [50.432110] 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> [50.432277] 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> [50.432433] 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> [50.432589] 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> [50.432745] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in avi infoframe
<7> [50.432901] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [50.433056] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [50.433058] i915 0000:00:02.0: colorspace: RGB
<7> [50.433060] i915 0000:00:02.0: scan mode: Underscan
<7> [50.433062] i915 0000:00:02.0: colorimetry: No Data
<7> [50.433063] i915 0000:00:02.0: picture aspect: No Data
<7> [50.433064] i915 0000:00:02.0: active aspect: Same as Picture
<7> [50.433066] i915 0000:00:02.0: itc: No Data
<7> [50.433067] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [50.433069] i915 0000:00:02.0: quantization range: Full
<7> [50.433070] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [50.433072] i915 0000:00:02.0: video code: 0
<7> [50.433073] i915 0000:00:02.0: ycc quantization range: Full
<7> [50.433074] i915 0000:00:02.0: hdmi content type: Graphics
<7> [50.433076] i915 0000:00:02.0: pixel repeat: 0
<7> [50.433077] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [50.433079] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [50.433247] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in spd infoframe
<7> [50.433404] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [50.433559] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [50.433561] i915 0000:00:02.0: vendor: Intel
<7> [50.433563] i915 0000:00:02.0: product: Integrated gfx
<7> [50.433564] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [50.433566] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [50.433723] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hdmi infoframe
<7> [50.433880] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [50.434035] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [50.434036] i915 0000:00:02.0: empty frame
<7> [50.434038] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [50.434194] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] fastset requirement not met, forcing full modeset
<7> [50.434366] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:170:pipe A] releasing DPLL 0
<7> [50.434558] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [50.434714] 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> [50.434904] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 1993) -> ( 0 - 0), size 1993 -> 0
<7> [50.435060] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:164:cursor A] ddb (1993 - 2048) -> ( 0 - 0), size 55 -> 0
<7> [50.435222] 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> [50.435376] 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> [50.435529] 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> [50.435681] 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> [50.435857] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:170:pipe A] data rate 0 num active planes 0
<7> [50.436021] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 0
<7> [50.436210] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:170:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [50.436392] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 17844 kHz -> 0 kHz
<7> [50.436576] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [50.436733] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz
<7> [50.436888] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [50.437048] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:170:pipe A] enable: no [modeset]
<7> [50.437214] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:plane 1A] fb: [NOFB], visible: no
<7> [50.437373] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [50.437531] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [50.437689] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [50.437847] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [50.438005] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:plane 6A] fb: [NOFB], visible: no
<7> [50.438162] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:154:plane 7A] fb: [NOFB], visible: no
<7> [50.438332] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:164:cursor A] fb: [NOFB], visible: no
<7> [50.438570] 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> [50.453775] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B
<7> [50.464070] 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> [50.464649] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [50.464868] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [50.465440] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [50.465693] 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> [50.465710] 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> [50.465902] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [50.471283] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [50.471543] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:170:pipe A]
<7> [50.471793] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [50.472020] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:34:plane 1A]
<7> [50.472271] 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> [50.472628] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:586:DDI B/PHY B]
<7> [50.472891] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:596:DDI TC1/PHY TC1]
<7> [50.473103] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:598:DP-MST A]
<7> [50.473334] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST B]
<7> [50.473524] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST C]
<7> [50.473700] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST D]
<7> [50.473875] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI TC2/PHY TC2]
<7> [50.474051] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:608:DP-MST A]
<7> [50.474244] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST B]
<7> [50.474497] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST C]
<7> [50.474720] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST D]
<7> [50.474898] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DDI TC3/PHY TC3]
<7> [50.475072] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:617:DP-MST A]
<7> [50.475278] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST B]
<7> [50.475527] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST C]
<7> [50.475700] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST D]
<7> [50.475880] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DDI TC4/PHY TC4]
<7> [50.476058] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST A]
<7> [50.476334] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST B]
<7> [50.476515] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST C]
<7> [50.476671] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST D]
<7> [50.476826] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:587:HDMI-A-1]
<7> [50.477275] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [50.477493] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [50.477676] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [50.477884] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:170:pipe A]
<7> [50.478522] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:308:pipe B]
<7> [50.478742] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:446:pipe C]
<7> [50.478987] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:584:pipe D]
<7> [50.479149] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:170:pipe A]
<7> [50.479183] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:587:HDMI-A-1]
<7> [50.479272] 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> [50.479454] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [50.479617] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [50.479857] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:586:DDI B/PHY B] [CRTC:170:pipe A]
<7> [50.480032] 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> [50.480194] 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> [50.480378] 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> [50.480571] 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> [50.480786] 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> [50.480947] 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> [50.481113] 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> [50.481289] 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> [50.481516] 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> [50.481690] 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> [50.481849] 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> [50.482006] 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> [50.482180] 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> [50.482424] 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> [50.482582] 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> [50.482739] 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> [50.482896] 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> [50.483103] 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> [50.483427] 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> [50.483585] 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> [50.483743] 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> [50.483900] 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> [50.484104] 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> [50.484350] 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> [50.484513] 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> [50.484674] 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> [50.484831] 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> [50.485048] 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> [50.485246] 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> [50.485405] 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> [50.485562] 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> [50.485726] 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> [50.485962] 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> [50.486119] 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> [50.486293] 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> [50.486457] 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> [50.486661] 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> [50.486890] 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> [50.487054] 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> [50.487229] 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> [50.487388] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in avi infoframe
<7> [50.487576] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [50.487788] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [50.487946] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [50.487949] i915 0000:00:02.0: colorspace: RGB
<7> [50.487950] i915 0000:00:02.0: scan mode: Underscan
<7> [50.487952] i915 0000:00:02.0: colorimetry: No Data
<7> [50.487953] i915 0000:00:02.0: picture aspect: No Data
<7> [50.487955] i915 0000:00:02.0: active aspect: Same as Picture
<7> [50.487956] i915 0000:00:02.0: itc: No Data
<7> [50.487958] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [50.487959] i915 0000:00:02.0: quantization range: Full
<7> [50.487960] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [50.487962] i915 0000:00:02.0: video code: 0
<7> [50.487963] i915 0000:00:02.0: ycc quantization range: Full
<7> [50.487965] i915 0000:00:02.0: hdmi content type: Graphics
<7> [50.487966] i915 0000:00:02.0: pixel repeat: 0
<7> [50.487967] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [50.487969] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in spd infoframe
<7> [50.488131] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [50.488329] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [50.488567] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [50.488570] i915 0000:00:02.0: vendor: Intel
<7> [50.488573] i915 0000:00:02.0: product: Integrated gfx
<7> [50.488576] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [50.488579] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hdmi infoframe
<7> [50.488816] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [50.489009] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [50.489168] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [50.489170] i915 0000:00:02.0: empty frame
<7> [50.489172] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] fastset requirement not met, forcing full modeset
<7> [50.489381] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [50.489547] 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> [50.489843] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 0) -> ( 0 - 1993), size 0 -> 1993
<7> [50.490020] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:164:cursor A] ddb ( 0 - 0) -> (1993 - 2048), size 0 -> 55
<7> [50.490175] 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> [50.490345] 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> [50.490514] 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> [50.490731] 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> [50.490906] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:170:pipe A] data rate 1142000 num active planes 1
<7> [50.491075] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 1142
<7> [50.491248] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [50.491485] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:170:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [50.491688] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 17844 kHz
<7> [50.491873] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [50.492030] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [50.492222] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [50.492453] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:170:pipe A] allocated DPLL 0
<7> [50.492659] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:170:pipe A] reserving DPLL 0
<7> [50.492827] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:170:pipe A] enable: yes [modeset]
<7> [50.492987] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [50.493147] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [50.493392] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [50.493572] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [50.493734] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [50.493891] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [50.494074] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [50.494311] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x0
<7> [50.494470] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [50.494472] i915 0000:00:02.0: colorspace: RGB
<7> [50.494474] i915 0000:00:02.0: scan mode: Underscan
<7> [50.494475] i915 0000:00:02.0: colorimetry: No Data
<7> [50.494477] i915 0000:00:02.0: picture aspect: No Data
<7> [50.494478] i915 0000:00:02.0: active aspect: Same as Picture
<7> [50.494480] i915 0000:00:02.0: itc: No Data
<7> [50.494481] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [50.494483] i915 0000:00:02.0: quantization range: Full
<7> [50.494484] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [50.494485] i915 0000:00:02.0: video code: 0
<7> [50.494487] i915 0000:00:02.0: ycc quantization range: Full
<7> [50.494488] i915 0000:00:02.0: hdmi content type: Graphics
<7> [50.494489] i915 0000:00:02.0: pixel repeat: 0
<7> [50.494491] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [50.494492] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [50.494494] i915 0000:00:02.0: vendor: Intel
<7> [50.494496] i915 0000:00:02.0: product: Integrated gfx
<7> [50.494497] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [50.494499] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [50.494500] i915 0000:00:02.0: empty frame
<7> [50.494502] 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> [50.494660] 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> [50.494816] 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> [50.495041] 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> [50.495293] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [50.495524] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [50.495688] 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> [50.495844] 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> [50.496005] 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> [50.496173] 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> [50.496426] 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> [50.496634] 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> [50.496792] 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> [50.496948] 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> [50.497105] 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> [50.497331] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [50.497522] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [50.497682] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [50.497840] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [50.498004] 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> [50.498034] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [50.498250] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [50.498309] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [50.498455] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [50.498573] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [50.498684] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [50.498904] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [50.499126] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [50.499359] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [50.499588] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [50.499814] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [50.500021] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [50.500261] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [50.500492] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [50.500714] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [50.500931] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [50.501158] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:plane 1A] fb: [FB:633] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [50.501404] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [50.501605] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [50.501840] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [50.502062] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [50.502306] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [50.502537] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [50.502761] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:plane 6A] fb: [NOFB], visible: no
<7> [50.502984] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:154:plane 7A] fb: [NOFB], visible: no
<7> [50.503264] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [50.503296] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<7> [50.503227] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:164:cursor A] fb: [NOFB], visible: no
<7> [50.504187] 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> [50.504558] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:586:DDI B/PHY B]
<7> [50.504794] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:596:DDI TC1/PHY TC1]
<7> [50.505026] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:598:DP-MST A]
<7> [50.505261] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST B]
<7> [50.505491] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST C]
<7> [50.505725] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST D]
<7> [50.505964] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI TC2/PHY TC2]
<7> [50.506187] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:608:DP-MST A]
<7> [50.506418] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST B]
<7> [50.506636] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST C]
<7> [50.506861] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST D]
<7> [50.507086] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DDI TC3/PHY TC3]
<7> [50.507329] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:617:DP-MST A]
<7> [50.507546] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST B]
<7> [50.507772] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST C]
<7> [50.507993] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST D]
<7> [50.508225] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DDI TC4/PHY TC4]
<7> [50.508453] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST A]
<7> [50.508685] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST B]
<7> [50.508908] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST C]
<7> [50.509122] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST D]
<7> [50.509576] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [50.509916] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:170:pipe A]
<7> [50.510160] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [50.510500] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [50.510847] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [50.511330] 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> [50.511948] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [50.512214] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [50.512807] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [50.513077] 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> [50.513101] 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> [50.517224] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [50.517480] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:34:plane 1A]
<7> [50.521813] 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> [50.530136] 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> [50.530424] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)
<7> [50.530645] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<6> [50.530879] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 5 not expected
<6> [50.530883] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 0 not expected
<6> [50.530886] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 10 not expected
<6> [50.530889] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 6 not expected
<7> [50.531074] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:587:HDMI-A-1]
<7> [50.531521] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:170:pipe A]
<7> [50.594556] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [50.599307] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [50.599341] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<7> [51.374467] 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> [51.374612] 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> [51.374525] 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> [51.374628] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling TC_cold_off
<7> [51.375434] i915 0000:00:02.0: [drm:tgl_tc_cold_request [i915]] TC cold unblock succeeded
<7> [51.375950] 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> [61.076303] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:170:pipe A]
<7> [61.076579] 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> [61.077170] 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> [61.077670] 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> [61.078143] 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> [61.078631] 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> [61.079095] 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> [61.079571] 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> [61.080041] 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> [61.080502] 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> [61.081025] 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> [61.081478] 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> [61.081650] 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> [61.081808] 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> [61.081966] 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> [61.082123] 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> [61.082281] 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> [61.082438] 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> [61.082608] 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> [61.082765] 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> [61.082922] 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> [61.083079] 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> [61.083236] 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> [61.083391] 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> [61.083556] 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> [61.083712] 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> [61.083869] 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> [61.084025] 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> [61.084181] 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> [61.084336] 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> [61.084500] 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> [61.084658] 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> [61.084815] 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> [61.084972] 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> [61.085127] 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> [61.085283] 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> [61.085439] 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> [61.085611] 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> [61.085766] 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> [61.085922] 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> [61.086078] 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> [61.086234] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in avi infoframe
<7> [61.086389] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [61.086555] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [61.086557] i915 0000:00:02.0: colorspace: RGB
<7> [61.086559] i915 0000:00:02.0: scan mode: Underscan
<7> [61.086560] i915 0000:00:02.0: colorimetry: No Data
<7> [61.086562] i915 0000:00:02.0: picture aspect: No Data
<7> [61.086563] i915 0000:00:02.0: active aspect: Same as Picture
<7> [61.086564] i915 0000:00:02.0: itc: No Data
<7> [61.086566] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [61.086567] i915 0000:00:02.0: quantization range: Full
<7> [61.086569] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [61.086570] i915 0000:00:02.0: video code: 0
<7> [61.086572] i915 0000:00:02.0: ycc quantization range: Full
<7> [61.086573] i915 0000:00:02.0: hdmi content type: Graphics
<7> [61.086575] i915 0000:00:02.0: pixel repeat: 0
<7> [61.086576] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [61.086578] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [61.086734] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in spd infoframe
<7> [61.086889] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [61.087044] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [61.087046] i915 0000:00:02.0: vendor: Intel
<7> [61.087047] i915 0000:00:02.0: product: Integrated gfx
<7> [61.087049] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [61.087050] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [61.087205] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hdmi infoframe
<7> [61.087361] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [61.087526] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [61.087528] i915 0000:00:02.0: empty frame
<7> [61.087529] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [61.087687] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] fastset requirement not met, forcing full modeset
<7> [61.087843] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:170:pipe A] releasing DPLL 0
<7> [61.088032] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [61.088191] 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> [61.088380] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 1993) -> ( 0 - 0), size 1993 -> 0
<7> [61.088552] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:164:cursor A] ddb (1993 - 2048) -> ( 0 - 0), size 55 -> 0
<7> [61.088708] 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> [61.088862] 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> [61.089014] 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> [61.089168] 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> [61.089340] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:170:pipe A] data rate 0 num active planes 0
<7> [61.089515] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 0
<7> [61.089697] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:170:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [61.089882] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 17844 kHz -> 0 kHz
<7> [61.090069] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [61.090227] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz
<7> [61.090384] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [61.090559] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:170:pipe A] enable: no [modeset]
<7> [61.090721] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:plane 1A] fb: [NOFB], visible: no
<7> [61.090882] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [61.091039] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [61.091197] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [61.091356] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [61.091527] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:plane 6A] fb: [NOFB], visible: no
<7> [61.091687] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:154:plane 7A] fb: [NOFB], visible: no
<7> [61.091844] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:164:cursor A] fb: [NOFB], visible: no
<7> [61.092080] 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> [61.106819] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B
<7> [61.117085] 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> [61.117693] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [61.117940] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [61.118535] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [61.118832] 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> [61.118851] 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> [61.119494] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [61.124013] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [61.124263] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:170:pipe A]
<7> [61.124503] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [61.124677] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:34:plane 1A]
<7> [61.124847] 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> [61.125174] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:586:DDI B/PHY B]
<7> [61.125384] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:596:DDI TC1/PHY TC1]
<7> [61.125544] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:598:DP-MST A]
<7> [61.125711] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST B]
<7> [61.125881] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST C]
<7> [61.126061] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST D]
<7> [61.126220] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI TC2/PHY TC2]
<7> [61.126408] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:608:DP-MST A]
<7> [61.126565] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST B]
<7> [61.126735] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST C]
<7> [61.126902] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST D]
<7> [61.127079] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DDI TC3/PHY TC3]
<7> [61.127263] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:617:DP-MST A]
<7> [61.127424] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST B]
<7> [61.127587] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST C]
<7> [61.127746] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST D]
<7> [61.127907] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DDI TC4/PHY TC4]
<7> [61.128091] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST A]
<7> [61.128262] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST B]
<7> [61.128425] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST C]
<7> [61.128580] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST D]
<7> [61.128741] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:587:HDMI-A-1]
<7> [61.129097] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [61.129328] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [61.129520] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [61.129709] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:170:pipe A]
<7> [61.130362] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:308:pipe B]
<7> [61.130532] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:446:pipe C]
<7> [61.130716] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:584:pipe D]
<7> [61.130856] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:170:pipe A]
<7> [61.130910] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:587:HDMI-A-1]
<7> [61.130988] 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> [61.131178] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [61.131340] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [61.131503] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:586:DDI B/PHY B] [CRTC:170:pipe A]
<7> [61.131700] 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> [61.131869] 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> [61.132049] 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> [61.132223] 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> [61.132393] 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> [61.132578] 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> [61.132746] 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> [61.132920] 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> [61.133084] 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> [61.133253] 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> [61.133431] 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> [61.133596] 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> [61.133754] 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> [61.133932] 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> [61.134100] 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> [61.134287] 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> [61.134457] 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> [61.134618] 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> [61.134785] 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> [61.134959] 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> [61.135140] 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> [61.135312] 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> [61.135470] 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> [61.135627] 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> [61.135784] 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> [61.135958] 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> [61.136115] 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> [61.136272] 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> [61.136429] 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> [61.136588] 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> [61.136744] 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> [61.136917] 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> [61.137077] 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> [61.137235] 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> [61.137391] 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> [61.137547] 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> [61.137712] 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> [61.137885] 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> [61.138053] 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> [61.138210] 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> [61.138368] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in avi infoframe
<7> [61.138524] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [61.138718] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [61.138889] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [61.138891] i915 0000:00:02.0: colorspace: RGB
<7> [61.138893] i915 0000:00:02.0: scan mode: Underscan
<7> [61.138894] i915 0000:00:02.0: colorimetry: No Data
<7> [61.138896] i915 0000:00:02.0: picture aspect: No Data
<7> [61.138897] i915 0000:00:02.0: active aspect: Same as Picture
<7> [61.138899] i915 0000:00:02.0: itc: No Data
<7> [61.138900] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [61.138902] i915 0000:00:02.0: quantization range: Full
<7> [61.138903] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [61.138905] i915 0000:00:02.0: video code: 0
<7> [61.138906] i915 0000:00:02.0: ycc quantization range: Full
<7> [61.138908] i915 0000:00:02.0: hdmi content type: Graphics
<7> [61.138909] i915 0000:00:02.0: pixel repeat: 0
<7> [61.138910] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [61.138912] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in spd infoframe
<7> [61.139083] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [61.139253] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [61.139412] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [61.139414] i915 0000:00:02.0: vendor: Intel
<7> [61.139416] i915 0000:00:02.0: product: Integrated gfx
<7> [61.139417] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [61.139419] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hdmi infoframe
<7> [61.139578] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [61.139734] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [61.139938] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [61.139940] i915 0000:00:02.0: empty frame
<7> [61.139942] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] fastset requirement not met, forcing full modeset
<7> [61.140131] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [61.140296] 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> [61.140496] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 0) -> ( 0 - 1993), size 0 -> 1993
<7> [61.140656] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:164:cursor A] ddb ( 0 - 0) -> (1993 - 2048), size 0 -> 55
<7> [61.140948] 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> [61.141116] 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> [61.141280] 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> [61.141434] 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> [61.141621] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:170:pipe A] data rate 1142000 num active planes 1
<7> [61.141849] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 1142
<7> [61.142031] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [61.142216] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:170:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [61.142405] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 17844 kHz
<7> [61.142626] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [61.142802] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [61.142980] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [61.143147] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:170:pipe A] allocated DPLL 0
<7> [61.143335] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:170:pipe A] reserving DPLL 0
<7> [61.143512] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:170:pipe A] enable: yes [modeset]
<7> [61.143682] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [61.143871] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [61.144045] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [61.144240] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [61.144401] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [61.144564] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [61.144738] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [61.144898] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x0
<7> [61.145091] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [61.145093] i915 0000:00:02.0: colorspace: RGB
<7> [61.145095] i915 0000:00:02.0: scan mode: Underscan
<7> [61.145096] i915 0000:00:02.0: colorimetry: No Data
<7> [61.145098] i915 0000:00:02.0: picture aspect: No Data
<7> [61.145099] i915 0000:00:02.0: active aspect: Same as Picture
<7> [61.145101] i915 0000:00:02.0: itc: No Data
<7> [61.145102] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [61.145103] i915 0000:00:02.0: quantization range: Full
<7> [61.145105] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [61.145106] i915 0000:00:02.0: video code: 0
<7> [61.145107] i915 0000:00:02.0: ycc quantization range: Full
<7> [61.145109] i915 0000:00:02.0: hdmi content type: Graphics
<7> [61.145110] i915 0000:00:02.0: pixel repeat: 0
<7> [61.145111] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [61.145113] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [61.145115] i915 0000:00:02.0: vendor: Intel
<7> [61.145116] i915 0000:00:02.0: product: Integrated gfx
<7> [61.145118] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [61.145119] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [61.145121] i915 0000:00:02.0: empty frame
<7> [61.145122] 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> [61.145279] 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> [61.145443] 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> [61.145606] 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> [61.145778] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [61.145935] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [61.146110] 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> [61.146287] 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> [61.146450] 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> [61.146606] 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> [61.146804] 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> [61.146987] 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> [61.147156] 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> [61.147322] 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> [61.147485] 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> [61.147649] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [61.147848] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [61.148012] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [61.148169] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [61.148332] 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> [61.148519] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [61.148688] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [61.148858] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [61.149028] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [61.149205] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [61.149375] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [61.149541] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [61.149713] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [61.149879] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [61.150051] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [61.150233] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [61.150399] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [61.150390] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [61.150563] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [61.150602] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [61.150766] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:plane 1A] fb: [FB:633] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [61.150877] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [61.150960] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [61.151152] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [61.151310] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [61.151476] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [61.151653] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [61.151838] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [61.152027] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:plane 6A] fb: [NOFB], visible: no
<7> [61.152183] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:154:plane 7A] fb: [NOFB], visible: no
<7> [61.152348] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:164:cursor A] fb: [NOFB], visible: no
<7> [61.152692] 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> [61.152917] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:586:DDI B/PHY B]
<7> [61.153099] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:596:DDI TC1/PHY TC1]
<7> [61.153277] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:598:DP-MST A]
<7> [61.153436] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST B]
<7> [61.153617] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST C]
<7> [61.153794] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST D]
<7> [61.153951] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI TC2/PHY TC2]
<7> [61.154155] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:608:DP-MST A]
<7> [61.154315] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST B]
<7> [61.154473] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST C]
<7> [61.154654] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST D]
<7> [61.154844] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DDI TC3/PHY TC3]
<7> [61.155012] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:617:DP-MST A]
<7> [61.155169] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST B]
<7> [61.155325] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST C]
<7> [61.155513] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST D]
<7> [61.155606] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [61.155639] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<7> [61.155683] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DDI TC4/PHY TC4]
<7> [61.155846] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST A]
<7> [61.156009] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST B]
<7> [61.156164] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST C]
<7> [61.156346] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST D]
<7> [61.156653] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [61.156912] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:170:pipe A]
<7> [61.157081] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [61.157611] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [61.157864] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [61.158194] 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> [61.158763] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [61.158979] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [61.159576] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [61.159751] 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> [61.159767] 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> [61.162498] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [61.162670] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:34:plane 1A]
<7> [61.168408] 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> [61.176752] 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> [61.176930] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)
<7> [61.177091] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<6> [61.177254] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 5 not expected
<6> [61.177257] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 0 not expected
<6> [61.177258] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 10 not expected
<6> [61.177260] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 6 not expected
<7> [61.177415] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:587:HDMI-A-1]
<7> [61.177676] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:170:pipe A]
<7> [61.233284] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [61.238068] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [61.238101] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<6> [69.002806] [IGT] kms_flip: finished subtest A-HDMI-A1, FAIL
Created at 2026-03-14 00:02:52