Results for igt@kms_flip@wf_vblank-ts-check@b-edp1

Result: Fail

i915_display_info14 igt_runner14 results14.json results14-xe-load.json guc_logs14.tar i915_display_info_post_exec14 serial_data14 boot14 dmesg14

DetailValue
Duration 25.98 seconds
Hostname 
shard-lnl-5
Igt-Version 
IGT-Version: 2.4-g833b81cd6 (x86_64) (Linux: 7.1.0-rc2-lgci-xe-xe-5032-7943b2aad82f5e3fc-debug+ x86_64)
Out 
Starting dynamic subtest: B-eDP1
  2880x1800: 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa 
Expected frametime: 16664us; measured 16663.5us +- 4.258us accuracy 0.08%
Event vblank: expected 602, counted 590, passrate = 33.90%, encoder type 2
Expected frametime: 16664us; measured 16862.9us +- 8.970us accuracy 0.16%
vblank interval differs from modeline! expected 16664.0us, measured 16863us +- 8.970us, difference 198.9us (22.2 sigma)
Event vblank: expected 596, counted 600, passrate = 40.00%, encoder type 2
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:1071 run_test_on_crtc_set()
  #2 ../tests/kms_flip.c:1920 run_test()
  #3 ../tests/kms_flip.c:2184 __igt_unique____real_main2105()
  #4 ../tests/kms_flip.c:2105 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest B-eDP1: FAIL (25.981s)
Err 
Starting dynamic subtest: B-eDP1
(kms_flip:4940) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1722:
(kms_flip:4940) CRITICAL: Failed assertion: !retried
Dynamic subtest B-eDP1 failed.
**** DEBUG ****
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 372.355835/1892, current 372.528259/1902: elapsed=172422.0us expected=166635.0us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 372.528259
last_received_ts = 372.527832
last_seq = 1902
current_ts = 372.700592
current_received_ts = 372.700226
current_seq = 1912
count = 33
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 372.528259/1902, current 372.700592/1912: elapsed=172344.0us expected=166635.0us +- 833.2us, error 3.4%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 372.528259/1902, current 372.700592/1912: elapsed=172344.0us expected=166635.0us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 372.700592
last_received_ts = 372.700226
last_seq = 1912
current_ts = 372.872955
current_received_ts = 372.872589
current_seq = 1922
count = 34
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 372.700592/1912, current 372.872955/1922: elapsed=172350.0us expected=166635.0us +- 833.2us, error 3.4%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 372.700592/1912, current 372.872955/1922: elapsed=172350.0us expected=166635.0us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 372.872955
last_received_ts = 372.872589
last_seq = 1922
current_ts = 373.045288
current_received_ts = 373.044861
current_seq = 1932
count = 35
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 372.872955/1922, current 373.045288/1932: elapsed=172345.0us expected=166635.0us +- 833.2us, error 3.4%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 372.872955/1922, current 373.045288/1932: elapsed=172345.0us expected=166635.0us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 373.045288
last_received_ts = 373.044861
last_seq = 1932
current_ts = 373.217651
current_received_ts = 373.217224
current_seq = 1942
count = 36
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 373.045288/1932, current 373.217651/1942: elapsed=172348.0us expected=166635.0us +- 833.2us, error 3.4%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 373.045288/1932, current 373.217651/1942: elapsed=172348.0us expected=166635.0us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 373.217651
last_received_ts = 373.217224
last_seq = 1942
current_ts = 373.389923
current_received_ts = 373.389587
current_seq = 1952
count = 37
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 373.217651/1942, current 373.389923/1952: elapsed=172278.0us expected=166635.0us +- 833.2us, error 3.4%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 373.217651/1942, current 373.389923/1952: elapsed=172278.0us expected=166635.0us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 373.389923
last_received_ts = 373.389587
last_seq = 1952
current_ts = 373.561554
current_received_ts = 373.561157
current_seq = 1962
count = 38
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 373.389923/1952, current 373.561554/1962: elapsed=171638.0us expected=166635.0us +- 833.2us, error 3.0%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 373.389923/1952, current 373.561554/1962: elapsed=171638.0us expected=166635.0us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 373.561554
last_received_ts = 373.561157
last_seq = 1962
current_ts = 373.733215
current_received_ts = 373.732819
current_seq = 1972
count = 39
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 373.561554/1962, current 373.733215/1972: elapsed=171650.0us expected=166635.0us +- 833.2us, error 3.0%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 373.561554/1962, current 373.733215/1972: elapsed=171650.0us expected=166635.0us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 373.733215
last_received_ts = 373.732819
last_seq = 1972
current_ts = 373.904846
current_received_ts = 373.904419
current_seq = 1982
count = 40
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 373.733215/1972, current 373.904846/1982: elapsed=171640.0us expected=166635.0us +- 833.2us, error 3.0%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 373.733215/1972, current 373.904846/1982: elapsed=171640.0us expected=166635.0us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 373.904846
last_received_ts = 373.904419
last_seq = 1982
current_ts = 374.076508
current_received_ts = 374.076141
current_seq = 1992
count = 41
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 373.904846/1982, current 374.076508/1992: elapsed=171646.0us expected=166635.0us +- 833.2us, error 3.0%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 373.904846/1982, current 374.076508/1992: elapsed=171646.0us expected=166635.0us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 374.076508
last_received_ts = 374.076141
last_seq = 1992
current_ts = 374.248138
current_received_ts = 374.247589
current_seq = 2002
count = 42
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 374.076508/1992, current 374.248138/2002: elapsed=171637.0us expected=166635.0us +- 833.2us, error 3.0%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 374.076508/1992, current 374.248138/2002: elapsed=171637.0us expected=166635.0us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 374.248138
last_received_ts = 374.247589
last_seq = 2002
current_ts = 374.419586
current_received_ts = 374.419189
current_seq = 2012
count = 43
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 374.248138/2002, current 374.419586/2012: elapsed=171463.0us expected=166635.0us +- 833.2us, error 2.9%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 374.248138/2002, current 374.419586/2012: elapsed=171463.0us expected=166635.0us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 374.419586
last_received_ts = 374.419189
last_seq = 2012
current_ts = 374.590637
current_received_ts = 374.590240
current_seq = 2022
count = 44
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 374.419586/2012, current 374.590637/2022: elapsed=171030.0us expected=166635.0us +- 833.2us, error 2.6%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 374.419586/2012, current 374.590637/2022: elapsed=171030.0us expected=166635.0us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 374.590637
last_received_ts = 374.590240
last_seq = 2022
current_ts = 374.761658
current_received_ts = 374.761292
current_seq = 2032
count = 45
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 374.590637/2022, current 374.761658/2032: elapsed=171028.0us expected=166635.0us +- 833.2us, error 2.6%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 374.590637/2022, current 374.761658/2032: elapsed=171028.0us expected=166635.0us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 374.761658
last_received_ts = 374.761292
last_seq = 2032
current_ts = 374.932678
current_received_ts = 374.932312
current_seq = 2042
count = 46
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 374.761658/2032, current 374.932678/2042: elapsed=171025.0us expected=166635.0us +- 833.2us, error 2.6%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 374.761658/2032, current 374.932678/2042: elapsed=171025.0us expected=166635.0us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 374.932678
last_received_ts = 374.932312
last_seq = 2042
current_ts = 375.103699
current_received_ts = 375.103241
current_seq = 2052
count = 47
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 374.932678/2042, current 375.103699/2052: elapsed=171027.0us expected=166635.0us +- 833.2us, error 2.6%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 374.932678/2042, current 375.103699/2052: elapsed=171027.0us expected=166635.0us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 375.103699
last_received_ts = 375.103241
last_seq = 2052
current_ts = 375.274750
current_received_ts = 375.274261
current_seq = 2062
count = 48
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 375.103699/2052, current 375.274750/2062: elapsed=171030.0us expected=166635.0us +- 833.2us, error 2.6%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 375.103699/2052, current 375.274750/2062: elapsed=171030.0us expected=166635.0us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 375.274750
last_received_ts = 375.274261
last_seq = 2062
current_ts = 375.445557
current_received_ts = 375.445160
current_seq = 2072
count = 49
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 375.274750/2062, current 375.445557/2072: elapsed=170812.0us expected=166635.0us +- 833.2us, error 2.5%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 375.274750/2062, current 375.445557/2072: elapsed=170812.0us expected=166635.0us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 375.445557
last_received_ts = 375.445160
last_seq = 2072
current_ts = 375.616058
current_received_ts = 375.615631
current_seq = 2082
count = 50
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 375.445557/2072, current 375.616058/2082: elapsed=170493.0us expected=166635.0us +- 833.2us, error 2.3%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 375.445557/2072, current 375.616058/2082: elapsed=170493.0us expected=166635.0us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 375.616058
last_received_ts = 375.615631
last_seq = 2082
current_ts = 375.786530
current_received_ts = 375.786133
current_seq = 2092
count = 51
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 375.616058/2082, current 375.786530/2092: elapsed=170489.0us expected=166635.0us +- 833.2us, error 2.3%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 375.616058/2082, current 375.786530/2092: elapsed=170489.0us expected=166635.0us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 375.786530
last_received_ts = 375.786133
last_seq = 2092
current_ts = 375.957031
current_received_ts = 375.956604
current_seq = 2102
count = 52
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 375.786530/2092, current 375.957031/2102: elapsed=170492.0us expected=166635.0us +- 833.2us, error 2.3%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 375.786530/2092, current 375.957031/2102: elapsed=170492.0us expected=166635.0us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 375.957031
last_received_ts = 375.956604
last_seq = 2102
current_ts = 376.127502
current_received_ts = 376.127167
current_seq = 2112
count = 53
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 375.957031/2102, current 376.127502/2112: elapsed=170487.0us expected=166635.0us +- 833.2us, error 2.3%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 375.957031/2102, current 376.127502/2112: elapsed=170487.0us expected=166635.0us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 376.127502
last_received_ts = 376.127167
last_seq = 2112
current_ts = 376.298004
current_received_ts = 376.297638
current_seq = 2122
count = 54
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 376.127502/2112, current 376.298004/2122: elapsed=170489.0us expected=166635.0us +- 833.2us, error 2.3%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 376.127502/2112, current 376.298004/2122: elapsed=170489.0us expected=166635.0us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 376.298004
last_received_ts = 376.297638
last_seq = 2122
current_ts = 376.468231
current_received_ts = 376.467773
current_seq = 2132
count = 55
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 376.298004/2122, current 376.468231/2132: elapsed=170215.0us expected=166635.0us +- 833.2us, error 2.1%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 376.298004/2122, current 376.468231/2132: elapsed=170215.0us expected=166635.0us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 376.468231
last_received_ts = 376.467773
last_seq = 2132
current_ts = 376.638245
current_received_ts = 376.637848
current_seq = 2142
count = 56
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 376.468231/2132, current 376.638245/2142: elapsed=170014.0us expected=166635.0us +- 833.2us, error 2.0%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 376.468231/2132, current 376.638245/2142: elapsed=170014.0us expected=166635.0us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 376.638245
last_received_ts = 376.637848
last_seq = 2142
current_ts = 376.808258
current_received_ts = 376.807831
current_seq = 2152
count = 57
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 376.638245/2142, current 376.808258/2152: elapsed=170020.0us expected=166635.0us +- 833.2us, error 2.0%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 376.638245/2142, current 376.808258/2152: elapsed=170020.0us expected=166635.0us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 376.808258
last_received_ts = 376.807831
last_seq = 2152
current_ts = 376.978271
current_received_ts = 376.977875
current_seq = 2162
count = 58
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 376.808258/2152, current 376.978271/2162: elapsed=170019.0us expected=166635.0us +- 833.2us, error 2.0%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 376.808258/2152, current 376.978271/2162: elapsed=170019.0us expected=166635.0us
(kms_flip:4940) INFO: Event vblank: expected 602, counted 590, passrate = 33.90%, encoder type 2
(kms_flip:4940) DEBUG: dropped frames, expected 602, counted 590, passrate = 33.90%, encoder type 2
(kms_flip:4940) DEBUG: Retrying without a hotplug event
(kms_flip:4940) DEBUG: No stale events found
(kms_flip:4940) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:4940) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:4940) INFO: Expected frametime: 16664us; measured 16862.9us +- 8.970us accuracy 0.16%
(kms_flip:4940) INFO: vblank interval differs from modeline! expected 16664.0us, measured 16863us +- 8.970us, difference 198.9us (22.2 sigma)
(kms_flip:4940) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 381.579681
current_received_ts = 381.579315
current_seq = 2194
count = 0
seq_step = 10
(kms_flip:4940) DEBUG: name = vblank
last_ts = 381.579681
last_received_ts = 381.579315
last_seq = 2194
current_ts = 381.748108
current_received_ts = 381.747711
current_seq = 2204
count = 1
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 381.579681/2194, current 381.748108/2204: elapsed=168418.0us expected=168629.4us +- 843.1us, error 0.1%
(kms_flip:4940) DEBUG: name = vblank
last_ts = 381.748108
last_received_ts = 381.747711
last_seq = 2204
current_ts = 381.916534
current_received_ts = 381.916168
current_seq = 2214
count = 2
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 381.748108/2204, current 381.916534/2214: elapsed=168411.0us expected=168629.4us +- 843.1us, error 0.1%
(kms_flip:4940) DEBUG: name = vblank
last_ts = 381.916534
last_received_ts = 381.916168
last_seq = 2214
current_ts = 382.084930
current_received_ts = 382.084534
current_seq = 2224
count = 3
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 381.916534/2214, current 382.084930/2224: elapsed=168414.0us expected=168629.4us +- 843.1us, error 0.1%
(kms_flip:4940) DEBUG: name = vblank
last_ts = 382.084930
last_received_ts = 382.084534
last_seq = 2224
current_ts = 382.253357
current_received_ts = 382.252930
current_seq = 2234
count = 4
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 382.084930/2224, current 382.253357/2234: elapsed=168411.0us expected=168629.4us +- 843.1us, error 0.1%
(kms_flip:4940) DEBUG: name = vblank
last_ts = 382.253357
last_received_ts = 382.252930
last_seq = 2234
current_ts = 382.421692
current_received_ts = 382.421295
current_seq = 2244
count = 5
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 382.253357/2234, current 382.421692/2244: elapsed=168358.0us expected=168629.4us +- 843.1us, error 0.2%
(kms_flip:4940) DEBUG: name = vblank
last_ts = 382.421692
last_received_ts = 382.421295
last_seq = 2244
current_ts = 382.589905
current_received_ts = 382.589508
current_seq = 2254
count = 6
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 382.421692/2244, current 382.589905/2254: elapsed=168201.0us expected=168629.4us +- 843.1us, error 0.3%
(kms_flip:4940) DEBUG: name = vblank
last_ts = 382.589905
last_received_ts = 382.589508
last_seq = 2254
current_ts = 382.758118
current_received_ts = 382.757690
current_seq = 2264
count = 7
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 382.589905/2254, current 382.758118/2264: elapsed=168198.0us expected=168629.4us +- 843.1us, error 0.3%
(kms_flip:4940) DEBUG: name = vblank
last_ts = 382.758118
last_received_ts = 382.757690
last_seq = 2264
current_ts = 382.926300
current_received_ts = 382.925964
current_seq = 2274
count = 8
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 382.758118/2264, current 382.926300/2274: elapsed=168206.0us expected=168629.4us +- 843.1us, error 0.3%
(kms_flip:4940) DEBUG: name = vblank
last_ts = 382.926300
last_received_ts = 382.925964
last_seq = 2274
current_ts = 383.094513
current_received_ts = 383.094147
current_seq = 2284
count = 9
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 382.926300/2274, current 383.094513/2284: elapsed=168207.0us expected=168629.4us +- 843.1us, error 0.3%
(kms_flip:4940) DEBUG: name = vblank
last_ts = 383.094513
last_received_ts = 383.094147
last_seq = 2284
current_ts = 383.262726
current_received_ts = 383.262299
current_seq = 2294
count = 10
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 383.094513/2284, current 383.262726/2294: elapsed=168202.0us expected=168629.4us +- 843.1us, error 0.3%
(kms_flip:4940) DEBUG: name = vblank
last_ts = 383.262726
last_received_ts = 383.262299
last_seq = 2294
current_ts = 383.430847
current_received_ts = 383.430450
current_seq = 2304
count = 11
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 383.262726/2294, current 383.430847/2304: elapsed=168140.0us expected=168629.4us +- 843.1us, error 0.3%
(kms_flip:4940) DEBUG: name = vblank
last_ts = 383.430847
last_received_ts = 383.430450
last_seq = 2304
current_ts = 383.598877
current_received_ts = 383.598480
current_seq = 2314
count = 12
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 383.430847/2304, current 383.598877/2314: elapsed=168022.0us expected=168629.4us +- 843.1us, error 0.4%
(kms_flip:4940) DEBUG: name = vblank
last_ts = 383.598877
last_received_ts = 383.598480
last_seq = 2314
current_ts = 383.766907
current_received_ts = 383.766479
current_seq = 2324
count = 13
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 383.598877/2314, current 383.766907/2324: elapsed=168014.0us expected=168629.4us +- 843.1us, error 0.4%
(kms_flip:4940) DEBUG: name = vblank
last_ts = 383.766907
last_received_ts = 383.766479
last_seq = 2324
current_ts = 383.934906
current_received_ts = 383.934479
current_seq = 2334
count = 14
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 383.766907/2324, current 383.934906/2334: elapsed=168020.0us expected=168629.4us +- 843.1us, error 0.4%
(kms_flip:4940) DEBUG: name = vblank
last_ts = 383.934906
last_received_ts = 383.934479
last_seq = 2334
current_ts = 384.102936
current_received_ts = 384.102539
current_seq = 2344
count = 15
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 383.934906/2334, current 384.102936/2344: elapsed=168018.0us expected=168629.4us +- 843.1us, error 0.4%
(kms_flip:4940) DEBUG: name = vblank
last_ts = 384.102936
last_received_ts = 384.102539
last_seq = 2344
current_ts = 384.270935
current_received_ts = 384.270538
current_seq = 2354
count = 16
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 384.102936/2344, current 384.270935/2354: elapsed=168014.0us expected=168629.4us +- 843.1us, error 0.4%
(kms_flip:4940) DEBUG: name = vblank
last_ts = 384.270935
last_received_ts = 384.270538
last_seq = 2354
current_ts = 384.438904
current_received_ts = 384.438477
current_seq = 2364
count = 17
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 384.270935/2354, current 384.438904/2364: elapsed=167953.0us expected=168629.4us +- 843.1us, error 0.4%
(kms_flip:4940) DEBUG: name = vblank
last_ts = 384.438904
last_received_ts = 384.438477
last_seq = 2364
current_ts = 384.606750
current_received_ts = 384.606354
current_seq = 2374
count = 18
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 384.438904/2364, current 384.606750/2374: elapsed=167856.0us expected=168629.4us +- 843.1us, error 0.5%
(kms_flip:4940) DEBUG: name = vblank
last_ts = 384.606750
last_received_ts = 384.606354
last_seq = 2374
current_ts = 384.774628
current_received_ts = 384.774231
current_seq = 2384
count = 19
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 384.606750/2374, current 384.774628/2384: elapsed=167862.0us expected=168629.4us +- 843.1us, error 0.5%
(kms_flip:4940) DEBUG: name = vblank
last_ts = 384.774628
last_received_ts = 384.774231
last_seq = 2384
current_ts = 384.942474
current_received_ts = 384.942078
current_seq = 2394
count = 20
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 384.774628/2384, current 384.942474/2394: elapsed=167854.0us expected=168629.4us +- 843.1us, error 0.5%
(kms_flip:4940) DEBUG: name = vblank
last_ts = 384.942474
last_received_ts = 384.942078
last_seq = 2394
current_ts = 385.110321
current_received_ts = 385.109924
current_seq = 2404
count = 21
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 384.942474/2394, current 385.110321/2404: elapsed=167858.0us expected=168629.4us +- 843.1us, error 0.5%
(kms_flip:4940) DEBUG: name = vblank
last_ts = 385.110321
last_received_ts = 385.109924
last_seq = 2404
current_ts = 385.278168
current_received_ts = 385.277740
current_seq = 2414
count = 22
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 385.110321/2404, current 385.278168/2414: elapsed=167851.0us expected=168629.4us +- 843.1us, error 0.5%
(kms_flip:4940) DEBUG: name = vblank
last_ts = 385.278168
last_received_ts = 385.277740
last_seq = 2414
current_ts = 385.445984
current_received_ts = 385.445526
current_seq = 2424
count = 23
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 385.278168/2414, current 385.445984/2424: elapsed=167801.0us expected=168629.4us +- 843.1us, error 0.5%
(kms_flip:4940) DEBUG: name = vblank
last_ts = 385.445984
last_received_ts = 385.445526
last_seq = 2424
current_ts = 385.613678
current_received_ts = 385.613281
current_seq = 2434
count = 24
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 385.445984/2424, current 385.613678/2434: elapsed=167712.0us expected=168629.4us +- 843.1us, error 0.5%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 385.445984/2424, current 385.613678/2434: elapsed=167712.0us expected=168629.4us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 385.613678
last_received_ts = 385.613281
last_seq = 2434
current_ts = 385.781403
current_received_ts = 385.780975
current_seq = 2444
count = 25
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 385.613678/2434, current 385.781403/2444: elapsed=167715.0us expected=168629.4us +- 843.1us, error 0.5%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 385.613678/2434, current 385.781403/2444: elapsed=167715.0us expected=168629.4us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 385.781403
last_received_ts = 385.780975
last_seq = 2444
current_ts = 385.949127
current_received_ts = 385.948669
current_seq = 2454
count = 26
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 385.781403/2444, current 385.949127/2454: elapsed=167720.0us expected=168629.4us +- 843.1us, error 0.5%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 385.781403/2444, current 385.949127/2454: elapsed=167720.0us expected=168629.4us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 385.949127
last_received_ts = 385.948669
last_seq = 2454
current_ts = 386.116852
current_received_ts = 386.116425
current_seq = 2464
count = 27
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 385.949127/2454, current 386.116852/2464: elapsed=167711.0us expected=168629.4us +- 843.1us, error 0.5%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 385.949127/2454, current 386.116852/2464: elapsed=167711.0us expected=168629.4us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 386.116852
last_received_ts = 386.116425
last_seq = 2464
current_ts = 386.284546
current_received_ts = 386.284088
current_seq = 2474
count = 28
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 386.116852/2464, current 386.284546/2474: elapsed=167716.0us expected=168629.4us +- 843.1us, error 0.5%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 386.116852/2464, current 386.284546/2474: elapsed=167716.0us expected=168629.4us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 386.284546
last_received_ts = 386.284088
last_seq = 2474
current_ts = 386.452209
current_received_ts = 386.451782
current_seq = 2484
count = 29
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 386.284546/2474, current 386.452209/2484: elapsed=167649.0us expected=168629.4us +- 843.1us, error 0.6%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 386.284546/2474, current 386.452209/2484: elapsed=167649.0us expected=168629.4us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 386.452209
last_received_ts = 386.451782
last_seq = 2484
current_ts = 386.619781
current_received_ts = 386.619385
current_seq = 2494
count = 30
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 386.452209/2484, current 386.619781/2494: elapsed=167590.0us expected=168629.4us +- 843.1us, error 0.6%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 386.452209/2484, current 386.619781/2494: elapsed=167590.0us expected=168629.4us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 386.619781
last_received_ts = 386.619385
last_seq = 2494
current_ts = 386.787384
current_received_ts = 386.786987
current_seq = 2504
count = 31
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 386.619781/2494, current 386.787384/2504: elapsed=167597.0us expected=168629.4us +- 843.1us, error 0.6%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 386.619781/2494, current 386.787384/2504: elapsed=167597.0us expected=168629.4us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 386.787384
last_received_ts = 386.786987
last_seq = 2504
current_ts = 386.954987
current_received_ts = 386.954407
current_seq = 2514
count = 32
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 386.787384/2504, current 386.954987/2514: elapsed=167584.0us expected=168629.4us +- 843.1us, error 0.6%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 386.787384/2504, current 386.954987/2514: elapsed=167584.0us expected=168629.4us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 386.954987
last_received_ts = 386.954407
last_seq = 2514
current_ts = 387.122559
current_received_ts = 387.121979
current_seq = 2524
count = 33
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 386.954987/2514, current 387.122559/2524: elapsed=167597.0us expected=168629.4us +- 843.1us, error 0.6%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 386.954987/2514, current 387.122559/2524: elapsed=167597.0us expected=168629.4us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 387.122559
last_received_ts = 387.121979
last_seq = 2524
current_ts = 387.290161
current_received_ts = 387.289703
current_seq = 2534
count = 34
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 387.122559/2524, current 387.290161/2534: elapsed=167591.0us expected=168629.4us +- 843.1us, error 0.6%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 387.122559/2524, current 387.290161/2534: elapsed=167591.0us expected=168629.4us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 387.290161
last_received_ts = 387.289703
last_seq = 2534
current_ts = 387.457703
current_received_ts = 387.457245
current_seq = 2544
count = 35
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 387.290161/2534, current 387.457703/2544: elapsed=167526.0us expected=168629.4us +- 843.1us, error 0.7%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 387.290161/2534, current 387.457703/2544: elapsed=167526.0us expected=168629.4us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 387.457703
last_received_ts = 387.457245
last_seq = 2544
current_ts = 387.625183
current_received_ts = 387.624756
current_seq = 2554
count = 36
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 387.457703/2544, current 387.625183/2554: elapsed=167483.0us expected=168629.4us +- 843.1us, error 0.7%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 387.457703/2544, current 387.625183/2554: elapsed=167483.0us expected=168629.4us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 387.625183
last_received_ts = 387.624756
last_seq = 2554
current_ts = 387.792664
current_received_ts = 387.792236
current_seq = 2564
count = 37
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 387.625183/2554, current 387.792664/2564: elapsed=167483.0us expected=168629.4us +- 843.1us, error 0.7%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 387.625183/2554, current 387.792664/2564: elapsed=167483.0us expected=168629.4us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 387.792664
last_received_ts = 387.792236
last_seq = 2564
current_ts = 387.960144
current_received_ts = 387.959686
current_seq = 2574
count = 38
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 387.792664/2564, current 387.960144/2574: elapsed=167482.0us expected=168629.4us +- 843.1us, error 0.7%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 387.792664/2564, current 387.960144/2574: elapsed=167482.0us expected=168629.4us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 387.960144
last_received_ts = 387.959686
last_seq = 2574
current_ts = 388.127625
current_received_ts = 388.127197
current_seq = 2584
count = 39
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 387.960144/2574, current 388.127625/2584: elapsed=167480.0us expected=168629.4us +- 843.1us, error 0.7%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 387.960144/2574, current 388.127625/2584: elapsed=167480.0us expected=168629.4us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 388.127625
last_received_ts = 388.127197
last_seq = 2584
current_ts = 388.295105
current_received_ts = 388.294678
current_seq = 2594
count = 40
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 388.127625/2584, current 388.295105/2594: elapsed=167485.0us expected=168629.4us +- 843.1us, error 0.7%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 388.127625/2584, current 388.295105/2594: elapsed=167485.0us expected=168629.4us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 388.295105
last_received_ts = 388.294678
last_seq = 2594
current_ts = 388.462524
current_received_ts = 388.462128
current_seq = 2604
count = 41
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 388.295105/2594, current 388.462524/2604: elapsed=167437.0us expected=168629.4us +- 843.1us, error 0.7%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 388.295105/2594, current 388.462524/2604: elapsed=167437.0us expected=168629.4us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 388.462524
last_received_ts = 388.462128
last_seq = 2604
current_ts = 388.629913
current_received_ts = 388.629547
current_seq = 2614
count = 42
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 388.462524/2604, current 388.629913/2614: elapsed=167387.0us expected=168629.4us +- 843.1us, error 0.7%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 388.462524/2604, current 388.629913/2614: elapsed=167387.0us expected=168629.4us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 388.629913
last_received_ts = 388.629547
last_seq = 2614
current_ts = 388.797302
current_received_ts = 388.796906
current_seq = 2624
count = 43
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 388.629913/2614, current 388.797302/2624: elapsed=167386.0us expected=168629.4us +- 843.1us, error 0.7%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 388.629913/2614, current 388.797302/2624: elapsed=167386.0us expected=168629.4us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 388.797302
last_received_ts = 388.796906
last_seq = 2624
current_ts = 388.964691
current_received_ts = 388.964264
current_seq = 2634
count = 44
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 388.797302/2624, current 388.964691/2634: elapsed=167386.0us expected=168629.4us +- 843.1us, error 0.7%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 388.797302/2624, current 388.964691/2634: elapsed=167386.0us expected=168629.4us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 388.964691
last_received_ts = 388.964264
last_seq = 2634
current_ts = 389.132080
current_received_ts = 389.131653
current_seq = 2644
count = 45
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 388.964691/2634, current 389.132080/2644: elapsed=167386.0us expected=168629.4us +- 843.1us, error 0.7%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 388.964691/2634, current 389.132080/2644: elapsed=167386.0us expected=168629.4us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 389.132080
last_received_ts = 389.131653
last_seq = 2644
current_ts = 389.299469
current_received_ts = 389.299072
current_seq = 2654
count = 46
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 389.132080/2644, current 389.299469/2654: elapsed=167391.0us expected=168629.4us +- 843.1us, error 0.7%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 389.132080/2644, current 389.299469/2654: elapsed=167391.0us expected=168629.4us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 389.299469
last_received_ts = 389.299072
last_seq = 2654
current_ts = 389.466797
current_received_ts = 389.466400
current_seq = 2664
count = 47
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 389.299469/2654, current 389.466797/2664: elapsed=167335.0us expected=168629.4us +- 843.1us, error 0.8%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 389.299469/2654, current 389.466797/2664: elapsed=167335.0us expected=168629.4us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 389.466797
last_received_ts = 389.466400
last_seq = 2664
current_ts = 389.634125
current_received_ts = 389.633728
current_seq = 2674
count = 48
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 389.466797/2664, current 389.634125/2674: elapsed=167311.0us expected=168629.4us +- 843.1us, error 0.8%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 389.466797/2664, current 389.634125/2674: elapsed=167311.0us expected=168629.4us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 389.634125
last_received_ts = 389.633728
last_seq = 2674
current_ts = 389.801422
current_received_ts = 389.801056
current_seq = 2684
count = 49
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 389.634125/2674, current 389.801422/2684: elapsed=167299.0us expected=168629.4us +- 843.1us, error 0.8%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 389.634125/2674, current 389.801422/2684: elapsed=167299.0us expected=168629.4us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 389.801422
last_received_ts = 389.801056
last_seq = 2684
current_ts = 389.968719
current_received_ts = 389.968292
current_seq = 2694
count = 50
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 389.801422/2684, current 389.968719/2694: elapsed=167305.0us expected=168629.4us +- 843.1us, error 0.8%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 389.801422/2684, current 389.968719/2694: elapsed=167305.0us expected=168629.4us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 389.968719
last_received_ts = 389.968292
last_seq = 2694
current_ts = 390.136017
current_received_ts = 390.135590
current_seq = 2704
count = 51
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 389.968719/2694, current 390.136017/2704: elapsed=167305.0us expected=168629.4us +- 843.1us, error 0.8%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 389.968719/2694, current 390.136017/2704: elapsed=167305.0us expected=168629.4us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 390.136017
last_received_ts = 390.135590
last_seq = 2704
current_ts = 390.303345
current_received_ts = 390.302917
current_seq = 2714
count = 52
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 390.136017/2704, current 390.303345/2714: elapsed=167303.0us expected=168629.4us +- 843.1us, error 0.8%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 390.136017/2704, current 390.303345/2714: elapsed=167303.0us expected=168629.4us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 390.303345
last_received_ts = 390.302917
last_seq = 2714
current_ts = 390.470581
current_received_ts = 390.470154
current_seq = 2724
count = 53
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 390.303345/2714, current 390.470581/2724: elapsed=167259.0us expected=168629.4us +- 843.1us, error 0.8%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 390.303345/2714, current 390.470581/2724: elapsed=167259.0us expected=168629.4us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 390.470581
last_received_ts = 390.470154
last_seq = 2724
current_ts = 390.637817
current_received_ts = 390.637390
current_seq = 2734
count = 54
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 390.470581/2724, current 390.637817/2734: elapsed=167234.0us expected=168629.4us +- 843.1us, error 0.8%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 390.470581/2724, current 390.637817/2734: elapsed=167234.0us expected=168629.4us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 390.637817
last_received_ts = 390.637390
last_seq = 2734
current_ts = 390.805054
current_received_ts = 390.804626
current_seq = 2744
count = 55
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 390.637817/2734, current 390.805054/2744: elapsed=167230.0us expected=168629.4us +- 843.1us, error 0.8%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 390.637817/2734, current 390.805054/2744: elapsed=167230.0us expected=168629.4us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 390.805054
last_received_ts = 390.804626
last_seq = 2744
current_ts = 390.972290
current_received_ts = 390.971832
current_seq = 2754
count = 56
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 390.805054/2744, current 390.972290/2754: elapsed=167237.0us expected=168629.4us +- 843.1us, error 0.8%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 390.805054/2744, current 390.972290/2754: elapsed=167237.0us expected=168629.4us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 390.972290
last_received_ts = 390.971832
last_seq = 2754
current_ts = 391.139526
current_received_ts = 391.139099
current_seq = 2764
count = 57
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 390.972290/2754, current 391.139526/2764: elapsed=167231.0us expected=168629.4us +- 843.1us, error 0.8%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 390.972290/2754, current 391.139526/2764: elapsed=167231.0us expected=168629.4us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 391.139526
last_received_ts = 391.139099
last_seq = 2764
current_ts = 391.306763
current_received_ts = 391.306366
current_seq = 2774
count = 58
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 391.139526/2764, current 391.306763/2774: elapsed=167230.0us expected=168629.4us +- 843.1us, error 0.8%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 391.139526/2764, current 391.306763/2774: elapsed=167230.0us expected=168629.4us
(kms_flip:4940) DEBUG: name = vblank
last_ts = 391.306763
last_received_ts = 391.306366
last_seq = 2774
current_ts = 391.473938
current_received_ts = 391.473511
current_seq = 2784
count = 59
seq_step = 10
(kms_flip:4940) DEBUG: vblank ts/seq: last 391.306763/2774, current 391.473938/2784: elapsed=167190.0us expected=168629.4us +- 843.1us, error 0.9%
(kms_flip:4940) DEBUG: inconsistent vblank ts/seq: last 391.306763/2774, current 391.473938/2784: elapsed=167190.0us expected=168629.4us
(kms_flip:4940) INFO: Event vblank: expected 596, counted 600, passrate = 40.00%, encoder type 2
(kms_flip:4940) DEBUG: dropped frames, expected 596, counted 600, passrate = 40.00%, encoder type 2
(kms_flip:4940) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1722:
(kms_flip:4940) CRITICAL: Failed assertion: !retried
(kms_flip:4940) igt_core-INFO: Stack trace:
(kms_flip:4940) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:4940) igt_core-INFO:   #1 ../tests/kms_flip.c:1071 run_test_on_crtc_set()
(kms_flip:4940) igt_core-INFO:   #2 ../tests/kms_flip.c:1920 run_test()
(kms_flip:4940) igt_core-INFO:   #3 ../tests/kms_flip.c:2184 __igt_unique____real_main2105()
(kms_flip:4940) igt_core-INFO:   #4 ../tests/kms_flip.c:2105 main()
(kms_flip:4940) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:4940) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:4940) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest B-eDP1: FAIL (25.981s)
Dmesg
<6> [423.501851] [IGT] kms_flip: starting dynamic subtest B-eDP1
<7> [423.504267] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:430]
<7> [423.506039] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:431]
<7> [423.512651] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [423.513112] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [423.513461] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [423.584847] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [423.585070] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [423.585391] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [423.585778] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [423.586013] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [423.586235] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in dp_m_n (expected tu 64 data 8102230/8388608 link 675185/524288, found tu 0, data 0/0 link 0/0)
<7> [423.586444] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [423.586901] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [423.587113] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [423.587301] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [423.587495] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [423.587670] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [423.587872] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [423.588064] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [423.588228] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [423.588390] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1800, found 0)
<7> [423.588572] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [423.588734] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [423.588889] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1906, found 0)
<7> [423.589061] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1906, found 0)
<7> [423.589231] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [423.589375] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [423.589531] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [423.589676] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [423.589824] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [423.589966] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [423.590104] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [423.590240] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1800, found 0)
<7> [423.590372] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [423.590545] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [423.590693] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1906, found 0)
<7> [423.590822] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1906, found 0)
<7> [423.590946] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [423.591068] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [423.591186] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [423.591307] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [423.591424] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 347710, found 0)
<7> [423.591554] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 347710, found 0)
<7> [423.591687] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 270000, found 0)
<7> [423.591821] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [423.591946] xe 0000:00:02.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:151:pipe A] releasing DPLL 0
<7> [423.592120] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [423.592224] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:151:pipe A] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [423.592348] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:145:cursor A] ddb (4051 - 4096) -> ( 0 - 0), size 45 -> 0
<7> [423.592437] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:151:pipe A] min cdclk: 173855 kHz -> 0 kHz
<7> [423.592578] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [423.592699] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 153600 kHz, actual 153600 kHz
<7> [423.592816] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [423.592978] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:151:pipe A] enable: no [modeset]
<7> [423.593105] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:35:plane 1A] fb: [NOFB], visible: no
<7> [423.593214] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [423.593319] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [423.593424] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [423.593541] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [423.593643] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [423.593799] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [423.593923] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [423.594177] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [423.594275] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [423.798802] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:393:eDP-1] set backlight PWM = 0
<7> [423.799426] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe A
<7> [423.813022] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turn panel power off
<7> [423.813488] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [423.813958] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - short
<7> [423.814476] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [423.814933] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [423.866262] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [423.866798] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:151:pipe A]
<7> [423.866849] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [423.867303] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [423.867714] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - short
<7> [423.868230] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [423.869345] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disabling DPLL 0
<7> [423.870062] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [423.870792] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:392:DDI A/PHY A]
<7> [423.871280] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DDI TC1/PHY TC1]
<7> [423.871794] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST A]
<7> [423.872263] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:404:DP-MST B]
<7> [423.872751] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:405:DP-MST C]
<7> [423.873184] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DDI TC2/PHY TC2]
<7> [423.873648] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST A]
<7> [423.874070] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:416:DP-MST B]
<7> [423.874510] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:417:DP-MST C]
<7> [423.874921] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DDI TC3/PHY TC3]
<7> [423.875333] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST A]
<7> [423.875795] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:424:DP-MST B]
<7> [423.876209] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:425:DP-MST C]
<7> [423.876662] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:393:eDP-1]
<7> [423.877187] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [423.877662] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [423.878053] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [423.878759] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_A
<7> [423.879253] xe 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x1 (CLKGATE_DIS_DSSDSC=0xaa000000)
<7> [423.879943] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:151:pipe A]
<7> [423.880328] xe 0000:00:02.0: [drm:intel_set_cdclk [xe]] Post changing CDCLK to 153600 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [423.880835] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [423.881139] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=384
<7> [423.881945] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:270:pipe B]
<7> [423.882300] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:389:pipe C]
<7> [423.882607] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:270:pipe B]
<7> [423.882672] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:393:eDP-1]
<7> [423.882832] xe 0000:00:02.0: [drm:compute_baseline_pipe_bpp [xe]] [CONNECTOR:393:eDP-1] Limiting target display pipe bpp to 24 (EDID bpp 30, max requested bpp 24, max platform bpp 36)
<7> [423.883179] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:392:DDI A/PHY A][CRTC:270:pipe B] DP link limits: pixel clock 347710 kHz DSC off max lanes 4 max rate 810000 max pipe_bpp 24 min link_bpp 18.0000 max link_bpp 24.0000
<7> [423.883471] xe 0000:00:02.0: [drm:intel_dp_compute_output_format [xe]] DP lane count 4 clock 270000 bpp input 24 compressed 0.0000 HDR no link rate required 1043130 available 1080000
<7> [423.883815] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:270:pipe B] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [423.884086] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:392:DDI A/PHY A] [CRTC:270:pipe B]
<7> [423.884349] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.enable (expected no, found yes)
<7> [423.884675] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.active (expected no, found yes)
<7> [423.884916] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in cpu_transcoder (expected -1, found 1)
<7> [423.885144] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in lane_count (expected 0, found 4)
<7> [423.885414] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 8102230/8388608 link 675185/524288)
<7> [423.885672] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [423.885909] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [423.886109] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [423.886321] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [423.886527] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)
<7> [423.886726] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)
<7> [423.886890] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)
<7> [423.887049] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)
<7> [423.887203] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [423.887352] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1800)
<7> [423.887516] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)
<7> [423.887679] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)
<7> [423.887820] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1906)
<7> [423.887956] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1906)
<7> [423.888086] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [423.888213] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [423.888337] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)
<7> [423.888458] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)
<7> [423.888611] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)
<7> [423.888749] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)
<7> [423.888864] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [423.888977] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1800)
<7> [423.889085] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)
<7> [423.889190] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)
<7> [423.889305] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1906)
<7> [423.889428] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1906)
<7> [423.889563] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [423.889668] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [423.889770] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [423.889866] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in dpll_hw_state
<7> [423.889962] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [423.890054] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: yes, tbt_mode: no
<7> [423.890145] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] c10pll_hw_state: fracen: no,
<7> [423.890234] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] multiplier: 225, tx_clk_div: 1.
<7> [423.890321] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] c10pll_rawhw_state:
<7> [423.890414] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] tx: 0x10, cmn: 0x21
<7> [423.890518] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1
<7> [423.890622] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [423.890736] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [423.890828] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [423.890905] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23
<7> [423.890990] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [423.891075] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: yes, tbt_mode: no
<7> [423.891153] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] c10pll_hw_state: fracen: yes,
<7> [423.891229] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] quot: 40960, rem: 0, den: 1,
<7> [423.891305] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] multiplier: 140, tx_clk_div: 1.
<7> [423.891385] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] c10pll_rawhw_state:
<7> [423.891466] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] tx: 0x10, cmn: 0x21
<7> [423.891610] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] pll[0] = 0xf4, pll[1] = 0x0, pll[2] = 0xf8, pll[3] = 0x0
<7> [423.891696] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [423.891776] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [423.891856] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] pll[12] = 0xa0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [423.891934] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] pll[16] = 0x84, pll[17] = 0x4f, pll[18] = 0xe5, pll[19] = 0x23
<7> [423.892013] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [423.892092] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 347710)
<7> [423.892172] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 347710)
<7> [423.892262] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in port_clock (expected 0, found 270000)
<7> [423.892356] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:270:pipe B] fastset requirement not met, forcing full modeset
<7> [423.892480] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [423.892577] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:270:pipe B] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x0 -> 0x2
<7> [423.892687] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:154:plane 1B] ddb ( 0 - 0) -> ( 0 - 4051), size 0 -> 4051
<7> [423.892767] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:264:cursor B] ddb ( 0 - 0) -> (4051 - 4096), size 0 -> 45
<7> [423.892833] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:154:plane 1B] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [423.892894] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:154:plane 1B] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 13, 13, 13, 13, 13, 0, 0, 0, 4, 0
<7> [423.892954] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:154:plane 1B] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 92, 297, 297, 297, 297, 297, 0, 0, 197, 92, 197
<7> [423.893012] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:154:plane 1B] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 183, 456, 456, 456, 456, 456, 0, 0, 198, 183, 198
<7> [423.893081] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:270:pipe B] data rate 1390840 num active planes 1
<7> [423.893176] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [423.893271] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [423.893376] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [423.893466] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [423.893571] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [423.893670] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:270:pipe B] min cdclk: 0 kHz -> 173855 kHz
<7> [423.893775] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [423.893864] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [423.893949] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [423.894046] xe 0000:00:02.0: [drm:intel_find_dpll [xe]] [CRTC:270:pipe B] allocated DPLL 0
<7> [423.894123] xe 0000:00:02.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:270:pipe B] reserving DPLL 0
<7> [423.894204] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:270:pipe B] enable: yes [modeset]
<7> [423.894291] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [423.894374] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: B, pipe bpp: 24, dithering: 0
<7> [423.894456] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [423.894558] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [423.894663] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [423.894742] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [423.894819] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m_n: lanes: 4; data_m: 8102230, data_n: 8388608, link_m: 675185, link_n: 524288, tu: 64
<7> [423.894898] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m2_n2: lanes: 4; data_m: 0, data_n: 0, link_m: 0, link_n: 0, tu: 0
<7> [423.894975] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [423.895052] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [423.895128] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled
<7> [423.895207] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum hblank: 0
<7> [423.895290] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [423.895373] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 4, length 14
<7> [423.895459] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [423.895554] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [423.895648] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [423.895733] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [423.895831] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [423.895913] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [423.895994] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [423.896075] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: no, fixed rr: yes, vmin: 1906, vmax: 1906, flipline: 1906, pipeline full: 0, guardband: 104 vsync start: 103, vsync end: 97
<7> [423.896156] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1802, vmax vblank: 1802, vmin vtotal: 1906, vmax vtotal: 1906
<7> [423.896229] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [423.896303] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] requested mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [423.896383] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [423.896470] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=347710, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1906 vs=1803-1809 vt=1906, flags=0xa
<7> [423.896570] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x40 0xa
<7> [423.896664] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=347710, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1906 vs=1803-1809 vt=1906, flags=0xa
<7> [423.896748] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 270000, pixel rate 347710, min cdclk 173855, min voltage level 0
<7> [423.896838] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [423.896934] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [423.897016] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe src: 2880x1800+0+0
<7> [423.897096] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [423.897177] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [423.897256] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [423.897330] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: yes, tbt_mode: no
<7> [423.897409] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_hw_state: fracen: yes,
<7> [423.897507] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] quot: 40960, rem: 0, den: 1,
<7> [423.897607] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] multiplier: 140, tx_clk_div: 1.
<7> [423.897697] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_rawhw_state:
<7> [423.897776] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] tx: 0x10, cmn: 0x21
<7> [423.897857] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[0] = 0xf4, pll[1] = 0x0, pll[2] = 0xf8, pll[3] = 0x0
<7> [423.897937] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [423.898016] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [423.898095] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[12] = 0xa0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [423.898173] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[16] = 0x84, pll[17] = 0x4f, pll[18] = 0xe5, pll[19] = 0x23
<7> [423.898246] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [423.898317] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [423.898390] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [423.898462] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [423.898555] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [423.898651] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [423.898730] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [423.898809] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [423.898887] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [423.898965] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [423.899054] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [423.899157] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [423.899265] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:154:plane 1B] fb: [FB:430] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [423.899375] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [423.899463] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [423.899571] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:184:plane 2B] fb: [NOFB], visible: no
<7> [423.899616] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [423.899691] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:214:plane 3B] fb: [NOFB], visible: no
<7> [423.899789] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:244:plane 4B] fb: [NOFB], visible: no
<7> [423.899825] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [423.899886] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:254:plane 5B] fb: [NOFB], visible: no
<7> [423.899949] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [423.899983] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:264:cursor B] fb: [NOFB], visible: no
<7> [423.900086] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling always-on
<7> [423.900348] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling always-on
<7> [423.900468] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [423.900606] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [423.900740] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [423.900866] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_B
<7> [423.901021] xe 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x2 (CLKGATE_DIS_DSSDSC=0xa2000000)
<7> [423.901133] xe 0000:00:02.0: [drm:intel_set_cdclk [xe]] Pre changing CDCLK to 192000 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [423.901236] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [423.901331] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=480
<7> [423.901423] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:392:DDI A/PHY A]
<7> [423.901532] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DDI TC1/PHY TC1]
<7> [423.901637] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST A]
<7> [423.901711] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:404:DP-MST B]
<7> [423.901783] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:405:DP-MST C]
<7> [423.901850] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DDI TC2/PHY TC2]
<7> [423.901914] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST A]
<7> [423.901976] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:416:DP-MST B]
<7> [423.902038] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:417:DP-MST C]
<7> [423.902101] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DDI TC3/PHY TC3]
<7> [423.902169] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST A]
<7> [423.902235] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:424:DP-MST B]
<7> [423.902302] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:425:DP-MST C]
<7> [423.902390] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [423.902562] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [423.902663] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 8 (mbus joined: yes)
<7> [423.902765] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [423.903622] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enable DPLL 0 (active 0x2, on? 0) for [CRTC:270:pipe B]
<7> [423.903741] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enabling DPLL 0
<7> [423.904870] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [423.904959] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - long
<7> [423.905043] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [423.905135] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [423.906002] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turn panel power on
<7> [423.906086] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 wait for panel power cycle (461 ms remaining)
<7> [424.374716] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [424.477123] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [424.477602] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 wait for panel power on
<7> [424.477993] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [424.514478] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [424.514877] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - long
<7> [424.515230] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [424.515704] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [424.688381] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [424.688981] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turning VDD on
<7> [424.689410] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [424.691856] xe 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX A/DDI A/PHY A: DPCD: 14 1e 44 c1 00 00 01 c0 02 00 02 00 00 0b 80
<7> [424.693657] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [424.694811] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 03
<7> [424.696386] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [424.698086] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [424.699460] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Clock recovery OK
<7> [424.700065] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Using DP training pattern TPS4
<7> [424.701864] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [424.702419] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Link Training passed at link rate = 270000, lane count = 4
<7> [424.703489] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe B
<7> [424.704097] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [424.704560] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe B
<7> [424.705112] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:393:eDP-1] set backlight PWM = 13892
<7> [424.737845] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR2
<7> [424.739440] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:393:eDP-1]
<7> [424.739857] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:270:pipe B]
<7> [424.760790] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [424.761392] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [424.761965] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [425.022637] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [425.023048] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [425.055778] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [425.056358] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [425.056939] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [427.270753] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turning VDD off
<7> [427.271220] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x00000067
<7> [437.844164] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [437.844803] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [437.845530] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [437.846331] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:270:pipe B]
<7> [437.846716] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [437.847333] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [437.848182] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [437.848848] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [437.849439] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in dp_m_n (expected tu 64 data 8102230/8388608 link 675185/524288, found tu 0, data 0/0 link 0/0)
<7> [437.850049] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [437.850697] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [437.851374] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [437.851905] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [437.852467] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [437.853119] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [437.853752] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [437.854335] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [437.854809] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [437.855259] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1800, found 0)
<7> [437.855766] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [437.856242] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [437.856770] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1906, found 0)
<7> [437.857211] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1906, found 0)
<7> [437.857711] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [437.858205] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [437.858750] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [437.859163] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [437.859601] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [437.859975] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [437.860374] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [437.860710] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1800, found 0)
<7> [437.861036] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [437.861368] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [437.861666] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1906, found 0)
<7> [437.861942] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1906, found 0)
<7> [437.862216] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [437.862472] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [437.862757] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [437.862990] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [437.863244] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 347710, found 0)
<7> [437.863389] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [437.863469] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 347710, found 0)
<7> [437.863689] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in port_clock (expected 270000, found 0)
<7> [437.863731] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [437.863895] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:270:pipe B] fastset requirement not met, forcing full modeset
<7> [437.863994] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [437.864101] xe 0000:00:02.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:270:pipe B] releasing DPLL 0
<7> [437.864391] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [437.864551] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:270:pipe B] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [437.864736] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:154:plane 1B] ddb ( 0 - 4051) -> ( 0 - 0), size 4051 -> 0
<7> [437.864882] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:264:cursor B] ddb (4051 - 4096) -> ( 0 - 0), size 45 -> 0
<7> [437.865018] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:154:plane 1B] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [437.865149] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:154:plane 1B] lines 4, 13, 13, 13, 13, 13, 0, 0, 0, 4, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [437.865308] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:154:plane 1B] blocks 92, 297, 297, 297, 297, 297, 0, 0, 197, 92, 197 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [437.865450] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:154:plane 1B] min_ddb 183, 456, 456, 456, 456, 456, 0, 0, 198, 183, 198 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [437.865635] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:270:pipe B] data rate 0 num active planes 0
<7> [437.865826] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 0 qgv_peak_bw: 38400
<7> [437.865999] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 0 qgv_peak_bw: 38400
<7> [437.866180] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [437.866360] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [437.866518] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 0
<7> [437.866685] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:270:pipe B] min cdclk: 173855 kHz -> 0 kHz
<7> [437.866875] xe 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 21732 kHz -> 0 kHz
<7> [437.867016] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [437.867160] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 153600 kHz, actual 153600 kHz
<7> [437.867294] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [437.867452] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:270:pipe B] enable: no [modeset]
<7> [437.867607] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:154:plane 1B] fb: [NOFB], visible: no
<7> [437.867757] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:184:plane 2B] fb: [NOFB], visible: no
<7> [437.867877] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:214:plane 3B] fb: [NOFB], visible: no
<7> [437.867994] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:244:plane 4B] fb: [NOFB], visible: no
<7> [437.868106] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:254:plane 5B] fb: [NOFB], visible: no
<7> [437.868230] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:264:cursor B] fb: [NOFB], visible: no
<7> [437.868391] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [437.868528] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [437.868704] xe 0000:00:02.0: [drm:intel_psr_disable_locked [xe]] Disabling PSR2
<7> [437.868901] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turning VDD on
<7> [437.869021] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006f
<7> [437.869630] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [437.869787] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [438.071563] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:393:eDP-1] set backlight PWM = 0
<7> [438.072293] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe B
<7> [438.087706] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turn panel power off
<7> [438.088211] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [438.088353] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [438.088885] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [438.088911] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - short
<7> [438.140948] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [438.141386] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - short
<7> [438.149733] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [438.150397] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disable DPLL 0 (active 0x2, on? 1) for [CRTC:270:pipe B]
<7> [438.150431] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [438.151061] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [438.152258] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disabling DPLL 0
<7> [438.152990] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [438.153774] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:392:DDI A/PHY A]
<7> [438.154321] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DDI TC1/PHY TC1]
<7> [438.154847] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST A]
<7> [438.155420] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:404:DP-MST B]
<7> [438.155920] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:405:DP-MST C]
<7> [438.156418] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DDI TC2/PHY TC2]
<7> [438.156853] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST A]
<7> [438.157348] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:416:DP-MST B]
<7> [438.157731] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:417:DP-MST C]
<7> [438.158118] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DDI TC3/PHY TC3]
<7> [438.158552] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST A]
<7> [438.158947] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:424:DP-MST B]
<7> [438.159345] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:425:DP-MST C]
<7> [438.159715] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:393:eDP-1]
<7> [438.160168] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [438.160532] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [438.160904] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [438.161555] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_B
<7> [438.162051] xe 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x2 (CLKGATE_DIS_DSSDSC=0xaa000000)
<7> [438.162655] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [438.163262] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:270:pipe B]
<7> [438.163644] xe 0000:00:02.0: [drm:intel_set_cdclk [xe]] Post changing CDCLK to 153600 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [438.164118] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [438.164436] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=384
<7> [438.165431] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:389:pipe C]
<7> [438.165836] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:270:pipe B]
<7> [438.165904] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:393:eDP-1]
<7> [438.166098] xe 0000:00:02.0: [drm:compute_baseline_pipe_bpp [xe]] [CONNECTOR:393:eDP-1] Limiting target display pipe bpp to 24 (EDID bpp 30, max requested bpp 24, max platform bpp 36)
<7> [438.166508] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:392:DDI A/PHY A][CRTC:270:pipe B] DP link limits: pixel clock 347710 kHz DSC off max lanes 4 max rate 810000 max pipe_bpp 24 min link_bpp 18.0000 max link_bpp 24.0000
<7> [438.166844] xe 0000:00:02.0: [drm:intel_dp_compute_output_format [xe]] DP lane count 4 clock 270000 bpp input 24 compressed 0.0000 HDR no link rate required 1043130 available 1080000
<7> [438.167168] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:270:pipe B] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [438.167448] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:392:DDI A/PHY A] [CRTC:270:pipe B]
<7> [438.167731] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.enable (expected no, found yes)
<7> [438.167987] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.active (expected no, found yes)
<7> [438.168292] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in cpu_transcoder (expected -1, found 1)
<7> [438.168597] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in lane_count (expected 0, found 4)
<7> [438.168837] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 8102230/8388608 link 675185/524288)
<7> [438.169087] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [438.169338] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [438.169560] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [438.169760] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [438.169945] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)
<7> [438.170156] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)
<7> [438.170347] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)
<7> [438.170514] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)
<7> [438.170682] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [438.170840] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1800)
<7> [438.170998] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)
<7> [438.171147] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)
<7> [438.171288] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1906)
<7> [438.171428] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1906)
<7> [438.171563] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [438.171698] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [438.171829] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)
<7> [438.171978] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)
<7> [438.172111] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)
<7> [438.172230] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)
<7> [438.172346] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [438.172462] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1800)
<7> [438.172574] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)
<7> [438.172685] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)
<7> [438.172797] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1906)
<7> [438.172922] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1906)
<7> [438.173119] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [438.173233] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [438.173335] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [438.173438] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [438.173539] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 347710)
<7> [438.173641] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 347710)
<7> [438.173749] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in port_clock (expected 0, found 270000)
<7> [438.173857] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:270:pipe B] fastset requirement not met, forcing full modeset
<7> [438.174015] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [438.174093] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:270:pipe B] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x0 -> 0x2
<7> [438.174217] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:154:plane 1B] ddb ( 0 - 0) -> ( 0 - 4051), size 0 -> 4051
<7> [438.174305] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:264:cursor B] ddb ( 0 - 0) -> (4051 - 4096), size 0 -> 45
<7> [438.174369] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:154:plane 1B] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [438.174429] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:154:plane 1B] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 13, 13, 13, 13, 13, 0, 0, 0, 4, 0
<7> [438.174489] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:154:plane 1B] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 92, 297, 297, 297, 297, 297, 0, 0, 197, 92, 197
<7> [438.174549] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:154:plane 1B] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 183, 456, 456, 456, 456, 456, 0, 0, 198, 183, 198
<7> [438.174622] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:270:pipe B] data rate 1390840 num active planes 1
<7> [438.174720] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [438.174812] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [438.174901] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [438.175007] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [438.175106] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [438.175194] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:270:pipe B] min cdclk: 0 kHz -> 173855 kHz
<7> [438.175297] xe 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 0 kHz -> 21732 kHz
<7> [438.175386] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [438.175471] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [438.175555] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [438.175654] xe 0000:00:02.0: [drm:intel_find_dpll [xe]] [CRTC:270:pipe B] allocated DPLL 0
<7> [438.175747] xe 0000:00:02.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:270:pipe B] reserving DPLL 0
<7> [438.175836] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:270:pipe B] enable: yes [modeset]
<7> [438.175937] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [438.176037] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: B, pipe bpp: 24, dithering: 0
<7> [438.176127] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [438.176217] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [438.176307] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [438.176403] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [438.176506] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m_n: lanes: 4; data_m: 8102230, data_n: 8388608, link_m: 675185, link_n: 524288, tu: 64
<7> [438.176608] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m2_n2: lanes: 4; data_m: 0, data_n: 0, link_m: 0, link_n: 0, tu: 0
<7> [438.176709] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [438.176798] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [438.176887] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled
<7> [438.176996] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum hblank: 0
<7> [438.177071] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [438.177153] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 4, length 14
<7> [438.177235] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [438.177316] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [438.177397] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [438.177477] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [438.177558] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [438.177638] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [438.177718] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [438.177798] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: no, fixed rr: yes, vmin: 1906, vmax: 1906, flipline: 1906, pipeline full: 0, guardband: 104 vsync start: 103, vsync end: 97
<7> [438.177876] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1802, vmax vblank: 1802, vmin vtotal: 1906, vmax vtotal: 1906
<7> [438.177964] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [438.178041] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] requested mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [438.178121] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [438.178201] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=347710, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1906 vs=1803-1809 vt=1906, flags=0xa
<7> [438.178281] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x40 0xa
<7> [438.178361] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=347710, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1906 vs=1803-1809 vt=1906, flags=0xa
<7> [438.178449] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 270000, pixel rate 347710, min cdclk 173855, min voltage level 0
<7> [438.178539] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [438.178628] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [438.178723] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe src: 2880x1800+0+0
<7> [438.178689] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [438.178817] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [438.178837] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - long
<7> [438.178924] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [438.178975] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [438.179020] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [438.179103] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: yes, tbt_mode: no
<7> [438.179087] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [438.179217] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_hw_state: fracen: yes,
<7> [438.179298] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] quot: 40960, rem: 0, den: 1,
<7> [438.179378] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] multiplier: 140, tx_clk_div: 1.
<7> [438.179459] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_rawhw_state:
<7> [438.179539] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] tx: 0x10, cmn: 0x21
<7> [438.179619] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[0] = 0xf4, pll[1] = 0x0, pll[2] = 0xf8, pll[3] = 0x0
<7> [438.179693] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [438.179767] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [438.179848] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[12] = 0xa0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [438.179934] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[16] = 0x84, pll[17] = 0x4f, pll[18] = 0xe5, pll[19] = 0x23
<7> [438.180032] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [438.180114] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [438.180197] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [438.180277] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [438.180355] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [438.180435] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [438.180515] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [438.180605] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [438.180699] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [438.180792] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [438.180919] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [438.180999] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [438.181073] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:154:plane 1B] fb: [FB:430] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [438.181150] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [438.181225] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [438.181299] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:184:plane 2B] fb: [NOFB], visible: no
<7> [438.181372] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:214:plane 3B] fb: [NOFB], visible: no
<7> [438.181454] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:244:plane 4B] fb: [NOFB], visible: no
<7> [438.181542] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:254:plane 5B] fb: [NOFB], visible: no
<7> [438.181633] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:264:cursor B] fb: [NOFB], visible: no
<7> [438.181934] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [438.182113] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_B
<7> [438.182286] xe 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x2 (CLKGATE_DIS_DSSDSC=0xa2000000)
<7> [438.182393] xe 0000:00:02.0: [drm:intel_set_cdclk [xe]] Pre changing CDCLK to 192000 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [438.182487] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [438.182577] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=480
<7> [438.182671] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:392:DDI A/PHY A]
<7> [438.182751] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DDI TC1/PHY TC1]
<7> [438.182844] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST A]
<7> [438.182937] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:404:DP-MST B]
<7> [438.183016] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:405:DP-MST C]
<7> [438.183105] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DDI TC2/PHY TC2]
<7> [438.183187] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST A]
<7> [438.183258] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:416:DP-MST B]
<7> [438.183328] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:417:DP-MST C]
<7> [438.183398] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DDI TC3/PHY TC3]
<7> [438.183467] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST A]
<7> [438.183530] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:424:DP-MST B]
<7> [438.183592] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:425:DP-MST C]
<7> [438.183674] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [438.183844] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [438.183937] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 8 (mbus joined: yes)
<7> [438.184050] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [438.184891] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enable DPLL 0 (active 0x2, on? 0) for [CRTC:270:pipe B]
<7> [438.185037] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enabling DPLL 0
<7> [438.187043] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turn panel power on
<7> [438.187135] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 wait for panel power cycle (463 ms remaining)
<7> [438.648316] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [438.678824] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [438.679306] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 wait for panel power on
<7> [438.679727] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [438.716173] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [438.716558] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - long
<7> [438.716878] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [438.717250] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [438.887679] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [438.888134] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turning VDD on
<7> [438.888583] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [438.890773] xe 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX A/DDI A/PHY A: DPCD: 14 1e 44 c1 00 00 01 c0 02 00 02 00 00 0b 80
<7> [438.892326] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [438.893274] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 03
<7> [438.894763] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [438.896395] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [438.897723] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Clock recovery OK
<7> [438.898288] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Using DP training pattern TPS4
<7> [438.899942] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [438.900504] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Link Training passed at link rate = 270000, lane count = 4
<7> [438.901540] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe B
<7> [438.902190] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [438.902678] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe B
<7> [438.903213] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:393:eDP-1] set backlight PWM = 13892
<7> [438.935956] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR2
<7> [438.937530] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:393:eDP-1]
<7> [438.937939] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:270:pipe B]
<7> [438.958668] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [438.959262] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [438.959831] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [439.220781] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [439.221189] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [439.253197] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [439.253784] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [439.254352] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [441.443735] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turning VDD off
<7> [441.444207] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x00000067
<6> [449.484545] [IGT] kms_flip: finished subtest B-eDP1, FAIL
Created at 2026-05-09 13:12:58