Results for igt@kms_flip@blocking-wf_vblank@a-edp1

Result: Fail

i915_display_info2 igt_runner2 results2.json results2-xe-load.json guc_logs2.tar i915_display_info_post_exec2 boot2 dmesg2

DetailValue
Duration 26.45 seconds
Hostname 
shard-lnl-5
Igt-Version 
IGT-Version: 2.3-gf57041a8e (x86_64) (Linux: 7.0.0-rc5-lgci-xe-xe-4769-0c7d9ab970be1e932-debug+ x86_64)
Out 
Using IGT_SRANDOM=1774379758 for randomisation
Opened device: /dev/dri/card0
Using monotonic timestamps
Starting subtest: blocking-wf_vblank
Starting dynamic subtest: A-eDP1
  2880x1800: 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa 
Expected frametime: 16664us; measured 16664.4us +- 1.746us accuracy 0.03%
Event vblank: expected 601, counted 610, passrate = 34.43%, encoder type 2
Expected frametime: 16664us; measured 16530.9us +- 3.732us accuracy 0.07%
vblank interval differs from modeline! expected 16664.0us, measured 16531us +- 3.732us, difference 133.1us (35.7 sigma)
Event vblank: expected 612, counted 610, passrate = 80.33%, encoder type 2
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1891 run_test()
  #3 ../tests/kms_flip.c:2126 __igt_unique____real_main2047()
  #4 ../tests/kms_flip.c:2047 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest A-eDP1: FAIL (26.449s)
Err 
Starting subtest: blocking-wf_vblank
Starting dynamic subtest: A-eDP1
(kms_flip:7363) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1711:
(kms_flip:7363) CRITICAL: Failed assertion: !retried
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
(kms_flip:7363) DEBUG: vblank ts/seq: last 214.048569/3990, current 214.210373/4000: elapsed=161806.0us expected=166643.8us +- 833.2us, error 2.9%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 214.048569/3990, current 214.210373/4000: elapsed=161806.0us expected=166643.8us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 214.210373
last_received_ts = 214.209885
last_seq = 4000
current_ts = 214.372726
current_received_ts = 214.372208
current_seq = 4010
count = 28
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 214.210373/4000, current 214.372726/4010: elapsed=162341.0us expected=166643.8us +- 833.2us, error 2.6%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 214.210373/4000, current 214.372726/4010: elapsed=162341.0us expected=166643.8us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 214.372726
last_received_ts = 214.372208
last_seq = 4010
current_ts = 214.535126
current_received_ts = 214.534653
current_seq = 4020
count = 29
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 214.372726/4010, current 214.535126/4020: elapsed=162406.0us expected=166643.8us +- 833.2us, error 2.5%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 214.372726/4010, current 214.535126/4020: elapsed=162406.0us expected=166643.8us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 214.535126
last_received_ts = 214.534653
last_seq = 4020
current_ts = 214.697525
current_received_ts = 214.697083
current_seq = 4030
count = 30
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 214.535126/4020, current 214.697525/4030: elapsed=162403.0us expected=166643.8us +- 833.2us, error 2.5%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 214.535126/4020, current 214.697525/4030: elapsed=162403.0us expected=166643.8us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 214.697525
last_received_ts = 214.697083
last_seq = 4030
current_ts = 214.859940
current_received_ts = 214.859436
current_seq = 4040
count = 31
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 214.697525/4030, current 214.859940/4040: elapsed=162404.0us expected=166643.8us +- 833.2us, error 2.5%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 214.697525/4030, current 214.859940/4040: elapsed=162404.0us expected=166643.8us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 214.859940
last_received_ts = 214.859436
last_seq = 4040
current_ts = 215.022324
current_received_ts = 215.021820
current_seq = 4050
count = 32
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 214.859940/4040, current 215.022324/4050: elapsed=162399.0us expected=166643.8us +- 833.2us, error 2.5%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 214.859940/4040, current 215.022324/4050: elapsed=162399.0us expected=166643.8us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 215.022324
last_received_ts = 215.021820
last_seq = 4050
current_ts = 215.184738
current_received_ts = 215.184204
current_seq = 4060
count = 33
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 215.022324/4050, current 215.184738/4060: elapsed=162403.0us expected=166643.8us +- 833.2us, error 2.5%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 215.022324/4050, current 215.184738/4060: elapsed=162403.0us expected=166643.8us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 215.184738
last_received_ts = 215.184204
last_seq = 4060
current_ts = 215.347580
current_received_ts = 215.347076
current_seq = 4070
count = 34
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 215.184738/4060, current 215.347580/4070: elapsed=162839.0us expected=166643.8us +- 833.2us, error 2.3%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 215.184738/4060, current 215.347580/4070: elapsed=162839.0us expected=166643.8us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 215.347580
last_received_ts = 215.347076
last_seq = 4070
current_ts = 215.510483
current_received_ts = 215.509750
current_seq = 4080
count = 35
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 215.347580/4070, current 215.510483/4080: elapsed=162916.0us expected=166643.8us +- 833.2us, error 2.2%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 215.347580/4070, current 215.510483/4080: elapsed=162916.0us expected=166643.8us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 215.510483
last_received_ts = 215.509750
last_seq = 4080
current_ts = 215.673416
current_received_ts = 215.672653
current_seq = 4090
count = 36
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 215.510483/4080, current 215.673416/4090: elapsed=162926.0us expected=166643.8us +- 833.2us, error 2.2%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 215.510483/4080, current 215.673416/4090: elapsed=162926.0us expected=166643.8us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 215.673416
last_received_ts = 215.672653
last_seq = 4090
current_ts = 215.836334
current_received_ts = 215.835602
current_seq = 4100
count = 37
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 215.673416/4090, current 215.836334/4100: elapsed=162922.0us expected=166643.8us +- 833.2us, error 2.2%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 215.673416/4090, current 215.836334/4100: elapsed=162922.0us expected=166643.8us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 215.836334
last_received_ts = 215.835602
last_seq = 4100
current_ts = 215.999268
current_received_ts = 215.998505
current_seq = 4110
count = 38
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 215.836334/4100, current 215.999268/4110: elapsed=162925.0us expected=166643.8us +- 833.2us, error 2.2%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 215.836334/4100, current 215.999268/4110: elapsed=162925.0us expected=166643.8us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 215.999268
last_received_ts = 215.998505
last_seq = 4110
current_ts = 216.162186
current_received_ts = 216.161331
current_seq = 4120
count = 39
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 215.999268/4110, current 216.162186/4120: elapsed=162924.0us expected=166643.8us +- 833.2us, error 2.2%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 215.999268/4110, current 216.162186/4120: elapsed=162924.0us expected=166643.8us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 216.162186
last_received_ts = 216.161331
last_seq = 4120
current_ts = 216.325409
current_received_ts = 216.324661
current_seq = 4130
count = 40
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 216.162186/4120, current 216.325409/4130: elapsed=163218.0us expected=166643.8us +- 833.2us, error 2.1%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 216.162186/4120, current 216.325409/4130: elapsed=163218.0us expected=166643.8us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 216.325409
last_received_ts = 216.324661
last_seq = 4130
current_ts = 216.488785
current_received_ts = 216.487930
current_seq = 4140
count = 41
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 216.325409/4130, current 216.488785/4140: elapsed=163382.0us expected=166643.8us +- 833.2us, error 2.0%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 216.325409/4130, current 216.488785/4140: elapsed=163382.0us expected=166643.8us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 216.488785
last_received_ts = 216.487930
last_seq = 4140
current_ts = 216.652161
current_received_ts = 216.651306
current_seq = 4150
count = 42
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 216.488785/4140, current 216.652161/4150: elapsed=163372.0us expected=166643.8us +- 833.2us, error 2.0%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 216.488785/4140, current 216.652161/4150: elapsed=163372.0us expected=166643.8us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 216.652161
last_received_ts = 216.651306
last_seq = 4150
current_ts = 216.815536
current_received_ts = 216.814682
current_seq = 4160
count = 43
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 216.652161/4150, current 216.815536/4160: elapsed=163381.0us expected=166643.8us +- 833.2us, error 2.0%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 216.652161/4150, current 216.815536/4160: elapsed=163381.0us expected=166643.8us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 216.815536
last_received_ts = 216.814682
last_seq = 4160
current_ts = 216.978912
current_received_ts = 216.978439
current_seq = 4170
count = 44
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 216.815536/4160, current 216.978912/4170: elapsed=163371.0us expected=166643.8us +- 833.2us, error 2.0%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 216.815536/4160, current 216.978912/4170: elapsed=163371.0us expected=166643.8us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 216.978912
last_received_ts = 216.978439
last_seq = 4170
current_ts = 217.142288
current_received_ts = 217.141693
current_seq = 4180
count = 45
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 216.978912/4170, current 217.142288/4180: elapsed=163380.0us expected=166643.8us +- 833.2us, error 2.0%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 216.978912/4170, current 217.142288/4180: elapsed=163380.0us expected=166643.8us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 217.142288
last_received_ts = 217.141693
last_seq = 4180
current_ts = 217.305878
current_received_ts = 217.305389
current_seq = 4190
count = 46
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 217.142288/4180, current 217.305878/4190: elapsed=163584.0us expected=166643.8us +- 833.2us, error 1.8%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 217.142288/4180, current 217.305878/4190: elapsed=163584.0us expected=166643.8us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 217.305878
last_received_ts = 217.305389
last_seq = 4190
current_ts = 217.469650
current_received_ts = 217.469193
current_seq = 4200
count = 47
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 217.305878/4190, current 217.469650/4200: elapsed=163773.0us expected=166643.8us +- 833.2us, error 1.7%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 217.305878/4190, current 217.469650/4200: elapsed=163773.0us expected=166643.8us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 217.469650
last_received_ts = 217.469193
last_seq = 4200
current_ts = 217.633423
current_received_ts = 217.632919
current_seq = 4210
count = 48
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 217.469650/4200, current 217.633423/4210: elapsed=163776.0us expected=166643.8us +- 833.2us, error 1.7%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 217.469650/4200, current 217.633423/4210: elapsed=163776.0us expected=166643.8us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 217.633423
last_received_ts = 217.632919
last_seq = 4210
current_ts = 217.797195
current_received_ts = 217.796692
current_seq = 4220
count = 49
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 217.633423/4210, current 217.797195/4220: elapsed=163770.0us expected=166643.8us +- 833.2us, error 1.7%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 217.633423/4210, current 217.797195/4220: elapsed=163770.0us expected=166643.8us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 217.797195
last_received_ts = 217.796692
last_seq = 4220
current_ts = 217.960968
current_received_ts = 217.960480
current_seq = 4230
count = 50
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 217.797195/4220, current 217.960968/4230: elapsed=163775.0us expected=166643.8us +- 833.2us, error 1.7%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 217.797195/4220, current 217.960968/4230: elapsed=163775.0us expected=166643.8us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 217.960968
last_received_ts = 217.960480
last_seq = 4230
current_ts = 218.124741
current_received_ts = 218.124252
current_seq = 4240
count = 51
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 217.960968/4230, current 218.124741/4240: elapsed=163775.0us expected=166643.8us +- 833.2us, error 1.7%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 217.960968/4230, current 218.124741/4240: elapsed=163775.0us expected=166643.8us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 218.124741
last_received_ts = 218.124252
last_seq = 4240
current_ts = 218.288651
current_received_ts = 218.288208
current_seq = 4250
count = 52
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 218.124741/4240, current 218.288651/4250: elapsed=163913.0us expected=166643.8us +- 833.2us, error 1.6%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 218.124741/4240, current 218.288651/4250: elapsed=163913.0us expected=166643.8us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 218.288651
last_received_ts = 218.288208
last_seq = 4250
current_ts = 218.452774
current_received_ts = 218.452271
current_seq = 4260
count = 53
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 218.288651/4250, current 218.452774/4260: elapsed=164122.0us expected=166643.8us +- 833.2us, error 1.5%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 218.288651/4250, current 218.452774/4260: elapsed=164122.0us expected=166643.8us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 218.452774
last_received_ts = 218.452271
last_seq = 4260
current_ts = 218.616898
current_received_ts = 218.616287
current_seq = 4270
count = 54
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 218.452774/4260, current 218.616898/4270: elapsed=164121.0us expected=166643.8us +- 833.2us, error 1.5%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 218.452774/4260, current 218.616898/4270: elapsed=164121.0us expected=166643.8us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 218.616898
last_received_ts = 218.616287
last_seq = 4270
current_ts = 218.781021
current_received_ts = 218.780548
current_seq = 4280
count = 55
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 218.616898/4270, current 218.781021/4280: elapsed=164117.0us expected=166643.8us +- 833.2us, error 1.5%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 218.616898/4270, current 218.781021/4280: elapsed=164117.0us expected=166643.8us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 218.781021
last_received_ts = 218.780548
last_seq = 4280
current_ts = 218.945145
current_received_ts = 218.944656
current_seq = 4290
count = 56
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 218.781021/4280, current 218.945145/4290: elapsed=164128.0us expected=166643.8us +- 833.2us, error 1.5%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 218.781021/4280, current 218.945145/4290: elapsed=164128.0us expected=166643.8us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 218.945145
last_received_ts = 218.944656
last_seq = 4290
current_ts = 219.109268
current_received_ts = 219.108780
current_seq = 4300
count = 57
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 218.945145/4290, current 219.109268/4300: elapsed=164122.0us expected=166643.8us +- 833.2us, error 1.5%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 218.945145/4290, current 219.109268/4300: elapsed=164122.0us expected=166643.8us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 219.109268
last_received_ts = 219.108780
last_seq = 4300
current_ts = 219.273483
current_received_ts = 219.273056
current_seq = 4310
count = 58
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 219.109268/4300, current 219.273483/4310: elapsed=164217.0us expected=166643.8us +- 833.2us, error 1.5%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 219.109268/4300, current 219.273483/4310: elapsed=164217.0us expected=166643.8us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 219.273483
last_received_ts = 219.273056
last_seq = 4310
current_ts = 219.437912
current_received_ts = 219.437454
current_seq = 4320
count = 59
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 219.273483/4310, current 219.437912/4320: elapsed=164429.0us expected=166643.8us +- 833.2us, error 1.3%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 219.273483/4310, current 219.437912/4320: elapsed=164429.0us expected=166643.8us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 219.437912
last_received_ts = 219.437454
last_seq = 4320
current_ts = 219.602341
current_received_ts = 219.601822
current_seq = 4330
count = 60
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 219.437912/4320, current 219.602341/4330: elapsed=164426.0us expected=166643.8us +- 833.2us, error 1.3%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 219.437912/4320, current 219.602341/4330: elapsed=164426.0us expected=166643.8us
(kms_flip:7363) INFO: Event vblank: expected 601, counted 610, passrate = 34.43%, encoder type 2
(kms_flip:7363) DEBUG: dropped frames, expected 601, counted 610, passrate = 34.43%, encoder type 2
(kms_flip:7363) DEBUG: Retrying without a hotplug event
(kms_flip:7363) DEBUG: No stale events found
(kms_flip:7363) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:7363) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:7363) INFO: Expected frametime: 16664us; measured 16530.9us +- 3.732us accuracy 0.07%
(kms_flip:7363) INFO: vblank interval differs from modeline! expected 16664.0us, measured 16531us +- 3.732us, difference 133.1us (35.7 sigma)
(kms_flip:7363) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 224.154907
current_received_ts = 224.154388
current_seq = 4361
count = 0
seq_step = 10
(kms_flip:7363) DEBUG: name = vblank
last_ts = 224.154907
last_received_ts = 224.154388
last_seq = 4361
current_ts = 224.320312
current_received_ts = 224.319778
current_seq = 4371
count = 1
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 224.154907/4361, current 224.320312/4371: elapsed=165400.0us expected=165309.4us +- 826.5us, error 0.1%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 224.320312
last_received_ts = 224.319778
last_seq = 4371
current_ts = 224.485764
current_received_ts = 224.485260
current_seq = 4381
count = 2
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 224.320312/4371, current 224.485764/4381: elapsed=165463.0us expected=165309.4us +- 826.5us, error 0.1%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 224.485764
last_received_ts = 224.485260
last_seq = 4381
current_ts = 224.651230
current_received_ts = 224.650711
current_seq = 4391
count = 3
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 224.485764/4381, current 224.651230/4391: elapsed=165464.0us expected=165309.4us +- 826.5us, error 0.1%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 224.651230
last_received_ts = 224.650711
last_seq = 4391
current_ts = 224.816711
current_received_ts = 224.816193
current_seq = 4401
count = 4
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 224.651230/4391, current 224.816711/4401: elapsed=165471.0us expected=165309.4us +- 826.5us, error 0.1%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 224.816711
last_received_ts = 224.816193
last_seq = 4401
current_ts = 224.982162
current_received_ts = 224.981659
current_seq = 4411
count = 5
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 224.816711/4401, current 224.982162/4411: elapsed=165460.0us expected=165309.4us +- 826.5us, error 0.1%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 224.982162
last_received_ts = 224.981659
last_seq = 4411
current_ts = 225.147629
current_received_ts = 225.147079
current_seq = 4421
count = 6
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 224.982162/4411, current 225.147629/4421: elapsed=165468.0us expected=165309.4us +- 826.5us, error 0.1%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 225.147629
last_received_ts = 225.147079
last_seq = 4421
current_ts = 225.313171
current_received_ts = 225.312668
current_seq = 4431
count = 7
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 225.147629/4421, current 225.313171/4431: elapsed=165545.0us expected=165309.4us +- 826.5us, error 0.1%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 225.313171
last_received_ts = 225.312668
last_seq = 4431
current_ts = 225.478775
current_received_ts = 225.478165
current_seq = 4441
count = 8
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 225.313171/4431, current 225.478775/4441: elapsed=165597.0us expected=165309.4us +- 826.5us, error 0.2%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 225.478775
last_received_ts = 225.478165
last_seq = 4441
current_ts = 225.644379
current_received_ts = 225.643875
current_seq = 4451
count = 9
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 225.478775/4441, current 225.644379/4451: elapsed=165606.0us expected=165309.4us +- 826.5us, error 0.2%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 225.644379
last_received_ts = 225.643875
last_seq = 4451
current_ts = 225.809982
current_received_ts = 225.809464
current_seq = 4461
count = 10
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 225.644379/4451, current 225.809982/4461: elapsed=165600.0us expected=165309.4us +- 826.5us, error 0.2%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 225.809982
last_received_ts = 225.809464
last_seq = 4461
current_ts = 225.975586
current_received_ts = 225.974991
current_seq = 4471
count = 11
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 225.809982/4461, current 225.975586/4471: elapsed=165600.0us expected=165309.4us +- 826.5us, error 0.2%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 225.975586
last_received_ts = 225.974991
last_seq = 4471
current_ts = 226.141174
current_received_ts = 226.140701
current_seq = 4481
count = 12
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 225.975586/4471, current 226.141174/4481: elapsed=165599.0us expected=165309.4us +- 826.5us, error 0.2%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 226.141174
last_received_ts = 226.140701
last_seq = 4481
current_ts = 226.306854
current_received_ts = 226.306351
current_seq = 4491
count = 13
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 226.141174/4481, current 226.306854/4491: elapsed=165667.0us expected=165309.4us +- 826.5us, error 0.2%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 226.306854
last_received_ts = 226.306351
last_seq = 4491
current_ts = 226.472565
current_received_ts = 226.472092
current_seq = 4501
count = 14
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 226.306854/4491, current 226.472565/4501: elapsed=165723.0us expected=165309.4us +- 826.5us, error 0.3%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 226.472565
last_received_ts = 226.472092
last_seq = 4501
current_ts = 226.638290
current_received_ts = 226.637802
current_seq = 4511
count = 15
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 226.472565/4501, current 226.638290/4511: elapsed=165718.0us expected=165309.4us +- 826.5us, error 0.2%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 226.638290
last_received_ts = 226.637802
last_seq = 4511
current_ts = 226.804001
current_received_ts = 226.803513
current_seq = 4521
count = 16
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 226.638290/4511, current 226.804001/4521: elapsed=165716.0us expected=165309.4us +- 826.5us, error 0.2%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 226.804001
last_received_ts = 226.803513
last_seq = 4521
current_ts = 226.969727
current_received_ts = 226.969269
current_seq = 4531
count = 17
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 226.804001/4521, current 226.969727/4531: elapsed=165721.0us expected=165309.4us +- 826.5us, error 0.2%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 226.969727
last_received_ts = 226.969269
last_seq = 4531
current_ts = 227.135452
current_received_ts = 227.134949
current_seq = 4541
count = 18
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 226.969727/4531, current 227.135452/4541: elapsed=165724.0us expected=165309.4us +- 826.5us, error 0.3%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 227.135452
last_received_ts = 227.134949
last_seq = 4541
current_ts = 227.301224
current_received_ts = 227.300735
current_seq = 4551
count = 19
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 227.135452/4541, current 227.301224/4551: elapsed=165770.0us expected=165309.4us +- 826.5us, error 0.3%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 227.301224
last_received_ts = 227.300735
last_seq = 4551
current_ts = 227.467041
current_received_ts = 227.466553
current_seq = 4561
count = 20
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 227.301224/4551, current 227.467041/4561: elapsed=165823.0us expected=165309.4us +- 826.5us, error 0.3%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 227.467041
last_received_ts = 227.466553
last_seq = 4561
current_ts = 227.632874
current_received_ts = 227.632385
current_seq = 4571
count = 21
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 227.467041/4561, current 227.632874/4571: elapsed=165825.0us expected=165309.4us +- 826.5us, error 0.3%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 227.632874
last_received_ts = 227.632385
last_seq = 4571
current_ts = 227.798691
current_received_ts = 227.798264
current_seq = 4581
count = 22
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 227.632874/4571, current 227.798691/4581: elapsed=165830.0us expected=165309.4us +- 826.5us, error 0.3%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 227.798691
last_received_ts = 227.798264
last_seq = 4581
current_ts = 227.964523
current_received_ts = 227.964096
current_seq = 4591
count = 23
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 227.798691/4581, current 227.964523/4591: elapsed=165825.0us expected=165309.4us +- 826.5us, error 0.3%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 227.964523
last_received_ts = 227.964096
last_seq = 4591
current_ts = 228.130341
current_received_ts = 228.129837
current_seq = 4601
count = 24
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 227.964523/4591, current 228.130341/4601: elapsed=165825.0us expected=165309.4us +- 826.5us, error 0.3%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 228.130341
last_received_ts = 228.129837
last_seq = 4601
current_ts = 228.296219
current_received_ts = 228.295761
current_seq = 4611
count = 25
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 228.130341/4601, current 228.296219/4611: elapsed=165868.0us expected=165309.4us +- 826.5us, error 0.3%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 228.296219
last_received_ts = 228.295761
last_seq = 4611
current_ts = 228.462128
current_received_ts = 228.461639
current_seq = 4621
count = 26
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 228.296219/4611, current 228.462128/4621: elapsed=165912.0us expected=165309.4us +- 826.5us, error 0.4%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 228.462128
last_received_ts = 228.461639
last_seq = 4621
current_ts = 228.628052
current_received_ts = 228.627594
current_seq = 4631
count = 27
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 228.462128/4621, current 228.628052/4631: elapsed=165921.0us expected=165309.4us +- 826.5us, error 0.4%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 228.628052
last_received_ts = 228.627594
last_seq = 4631
current_ts = 228.793961
current_received_ts = 228.793518
current_seq = 4641
count = 28
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 228.628052/4631, current 228.793961/4641: elapsed=165917.0us expected=165309.4us +- 826.5us, error 0.4%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 228.793961
last_received_ts = 228.793518
last_seq = 4641
current_ts = 228.959885
current_received_ts = 228.959427
current_seq = 4651
count = 29
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 228.793961/4641, current 228.959885/4651: elapsed=165914.0us expected=165309.4us +- 826.5us, error 0.4%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 228.959885
last_received_ts = 228.959427
last_seq = 4651
current_ts = 229.125793
current_received_ts = 229.125198
current_seq = 4661
count = 30
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 228.959885/4651, current 229.125793/4661: elapsed=165914.0us expected=165309.4us +- 826.5us, error 0.4%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 229.125793
last_received_ts = 229.125198
last_seq = 4661
current_ts = 229.291748
current_received_ts = 229.291290
current_seq = 4671
count = 31
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 229.125793/4661, current 229.291748/4671: elapsed=165954.0us expected=165309.4us +- 826.5us, error 0.4%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 229.291748
last_received_ts = 229.291290
last_seq = 4671
current_ts = 229.457748
current_received_ts = 229.457123
current_seq = 4681
count = 32
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 229.291748/4671, current 229.457748/4681: elapsed=165999.0us expected=165309.4us +- 826.5us, error 0.4%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 229.457748
last_received_ts = 229.457123
last_seq = 4681
current_ts = 229.623749
current_received_ts = 229.623260
current_seq = 4691
count = 33
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 229.457748/4681, current 229.623749/4691: elapsed=165997.0us expected=165309.4us +- 826.5us, error 0.4%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 229.623749
last_received_ts = 229.623260
last_seq = 4691
current_ts = 229.789734
current_received_ts = 229.789261
current_seq = 4701
count = 34
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 229.623749/4691, current 229.789734/4701: elapsed=165993.0us expected=165309.4us +- 826.5us, error 0.4%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 229.789734
last_received_ts = 229.789261
last_seq = 4701
current_ts = 229.955734
current_received_ts = 229.955170
current_seq = 4711
count = 35
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 229.789734/4701, current 229.955734/4711: elapsed=165995.0us expected=165309.4us +- 826.5us, error 0.4%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 229.955734
last_received_ts = 229.955170
last_seq = 4711
current_ts = 230.121735
current_received_ts = 230.121216
current_seq = 4721
count = 36
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 229.955734/4711, current 230.121735/4721: elapsed=165998.0us expected=165309.4us +- 826.5us, error 0.4%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 230.121735
last_received_ts = 230.121216
last_seq = 4721
current_ts = 230.287750
current_received_ts = 230.287247
current_seq = 4731
count = 37
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 230.121735/4721, current 230.287750/4731: elapsed=166025.0us expected=165309.4us +- 826.5us, error 0.4%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 230.287750
last_received_ts = 230.287247
last_seq = 4731
current_ts = 230.453827
current_received_ts = 230.453339
current_seq = 4741
count = 38
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 230.287750/4731, current 230.453827/4741: elapsed=166070.0us expected=165309.4us +- 826.5us, error 0.5%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 230.453827
last_received_ts = 230.453339
last_seq = 4741
current_ts = 230.619888
current_received_ts = 230.619385
current_seq = 4751
count = 39
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 230.453827/4741, current 230.619888/4751: elapsed=166065.0us expected=165309.4us +- 826.5us, error 0.5%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 230.619888
last_received_ts = 230.619385
last_seq = 4751
current_ts = 230.785965
current_received_ts = 230.785446
current_seq = 4761
count = 40
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 230.619888/4751, current 230.785965/4761: elapsed=166069.0us expected=165309.4us +- 826.5us, error 0.5%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 230.785965
last_received_ts = 230.785446
last_seq = 4761
current_ts = 230.952026
current_received_ts = 230.951431
current_seq = 4771
count = 41
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 230.785965/4761, current 230.952026/4771: elapsed=166063.0us expected=165309.4us +- 826.5us, error 0.5%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 230.952026
last_received_ts = 230.951431
last_seq = 4771
current_ts = 231.118088
current_received_ts = 231.117569
current_seq = 4781
count = 42
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 230.952026/4771, current 231.118088/4781: elapsed=166063.0us expected=165309.4us +- 826.5us, error 0.5%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 231.118088
last_received_ts = 231.117569
last_seq = 4781
current_ts = 231.284180
current_received_ts = 231.283707
current_seq = 4791
count = 43
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 231.118088/4781, current 231.284180/4791: elapsed=166093.0us expected=165309.4us +- 826.5us, error 0.5%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 231.284180
last_received_ts = 231.283707
last_seq = 4791
current_ts = 231.450317
current_received_ts = 231.449860
current_seq = 4801
count = 44
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 231.284180/4791, current 231.450317/4801: elapsed=166132.0us expected=165309.4us +- 826.5us, error 0.5%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 231.450317
last_received_ts = 231.449860
last_seq = 4801
current_ts = 231.616440
current_received_ts = 231.615952
current_seq = 4811
count = 45
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 231.450317/4801, current 231.616440/4811: elapsed=166127.0us expected=165309.4us +- 826.5us, error 0.5%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 231.616440
last_received_ts = 231.615952
last_seq = 4811
current_ts = 231.782562
current_received_ts = 231.782089
current_seq = 4821
count = 46
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 231.616440/4811, current 231.782562/4821: elapsed=166129.0us expected=165309.4us +- 826.5us, error 0.5%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 231.782562
last_received_ts = 231.782089
last_seq = 4821
current_ts = 231.948685
current_received_ts = 231.948257
current_seq = 4831
count = 47
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 231.782562/4821, current 231.948685/4831: elapsed=166121.0us expected=165309.4us +- 826.5us, error 0.5%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 231.948685
last_received_ts = 231.948257
last_seq = 4831
current_ts = 232.114822
current_received_ts = 232.114288
current_seq = 4841
count = 48
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 231.948685/4831, current 232.114822/4841: elapsed=166133.0us expected=165309.4us +- 826.5us, error 0.5%
(kms_flip:7363) DEBUG: name = vblank
last_ts = 232.114822
last_received_ts = 232.114288
last_seq = 4841
current_ts = 232.280960
current_received_ts = 232.280396
current_seq = 4851
count = 49
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 232.114822/4841, current 232.280960/4851: elapsed=166146.0us expected=165309.4us +- 826.5us, error 0.5%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 232.114822/4841, current 232.280960/4851: elapsed=166146.0us expected=165309.4us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 232.280960
last_received_ts = 232.280396
last_seq = 4851
current_ts = 232.447144
current_received_ts = 232.446686
current_seq = 4861
count = 50
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 232.280960/4851, current 232.447144/4861: elapsed=166179.0us expected=165309.4us +- 826.5us, error 0.5%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 232.280960/4851, current 232.447144/4861: elapsed=166179.0us expected=165309.4us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 232.447144
last_received_ts = 232.446686
last_seq = 4861
current_ts = 232.613327
current_received_ts = 232.612854
current_seq = 4871
count = 51
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 232.447144/4861, current 232.613327/4871: elapsed=166185.0us expected=165309.4us +- 826.5us, error 0.5%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 232.447144/4861, current 232.613327/4871: elapsed=166185.0us expected=165309.4us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 232.613327
last_received_ts = 232.612854
last_seq = 4871
current_ts = 232.779510
current_received_ts = 232.779083
current_seq = 4881
count = 52
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 232.613327/4871, current 232.779510/4881: elapsed=166185.0us expected=165309.4us +- 826.5us, error 0.5%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 232.613327/4871, current 232.779510/4881: elapsed=166185.0us expected=165309.4us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 232.779510
last_received_ts = 232.779083
last_seq = 4881
current_ts = 232.945694
current_received_ts = 232.945221
current_seq = 4891
count = 53
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 232.779510/4881, current 232.945694/4891: elapsed=166182.0us expected=165309.4us +- 826.5us, error 0.5%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 232.779510/4881, current 232.945694/4891: elapsed=166182.0us expected=165309.4us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 232.945694
last_received_ts = 232.945221
last_seq = 4891
current_ts = 233.111877
current_received_ts = 233.111313
current_seq = 4901
count = 54
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 232.945694/4891, current 233.111877/4901: elapsed=166178.0us expected=165309.4us +- 826.5us, error 0.5%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 232.945694/4891, current 233.111877/4901: elapsed=166178.0us expected=165309.4us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 233.111877
last_received_ts = 233.111313
last_seq = 4901
current_ts = 233.278076
current_received_ts = 233.277466
current_seq = 4911
count = 55
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 233.111877/4901, current 233.278076/4911: elapsed=166198.0us expected=165309.4us +- 826.5us, error 0.5%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 233.111877/4901, current 233.278076/4911: elapsed=166198.0us expected=165309.4us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 233.278076
last_received_ts = 233.277466
last_seq = 4911
current_ts = 233.444305
current_received_ts = 233.443848
current_seq = 4921
count = 56
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 233.278076/4911, current 233.444305/4921: elapsed=166227.0us expected=165309.4us +- 826.5us, error 0.6%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 233.278076/4911, current 233.444305/4921: elapsed=166227.0us expected=165309.4us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 233.444305
last_received_ts = 233.443848
last_seq = 4921
current_ts = 233.610535
current_received_ts = 233.610062
current_seq = 4931
count = 57
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 233.444305/4921, current 233.610535/4931: elapsed=166229.0us expected=165309.4us +- 826.5us, error 0.6%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 233.444305/4921, current 233.610535/4931: elapsed=166229.0us expected=165309.4us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 233.610535
last_received_ts = 233.610062
last_seq = 4931
current_ts = 233.776749
current_received_ts = 233.776276
current_seq = 4941
count = 58
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 233.610535/4931, current 233.776749/4941: elapsed=166226.0us expected=165309.4us +- 826.5us, error 0.6%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 233.610535/4931, current 233.776749/4941: elapsed=166226.0us expected=165309.4us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 233.776749
last_received_ts = 233.776276
last_seq = 4941
current_ts = 233.942978
current_received_ts = 233.942535
current_seq = 4951
count = 59
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 233.776749/4941, current 233.942978/4951: elapsed=166225.0us expected=165309.4us +- 826.5us, error 0.6%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 233.776749/4941, current 233.942978/4951: elapsed=166225.0us expected=165309.4us
(kms_flip:7363) DEBUG: name = vblank
last_ts = 233.942978
last_received_ts = 233.942535
last_seq = 4951
current_ts = 234.109207
current_received_ts = 234.108704
current_seq = 4961
count = 60
seq_step = 10
(kms_flip:7363) DEBUG: vblank ts/seq: last 233.942978/4951, current 234.109207/4961: elapsed=166233.0us expected=165309.4us +- 826.5us, error 0.6%
(kms_flip:7363) DEBUG: inconsistent vblank ts/seq: last 233.942978/4951, current 234.109207/4961: elapsed=166233.0us expected=165309.4us
(kms_flip:7363) INFO: Event vblank: expected 612, counted 610, passrate = 80.33%, encoder type 2
(kms_flip:7363) DEBUG: dropped frames, expected 612, counted 610, passrate = 80.33%, encoder type 2
(kms_flip:7363) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1711:
(kms_flip:7363) CRITICAL: Failed assertion: !retried
(kms_flip:7363) igt_core-INFO: Stack trace:
(kms_flip:7363) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:7363) igt_core-INFO:   #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
(kms_flip:7363) igt_core-INFO:   #2 ../tests/kms_flip.c:1891 run_test()
(kms_flip:7363) igt_core-INFO:   #3 ../tests/kms_flip.c:2126 __igt_unique____real_main2047()
(kms_flip:7363) igt_core-INFO:   #4 ../tests/kms_flip.c:2047 main()
(kms_flip:7363) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:7363) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:7363) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-eDP1: FAIL (26.449s)
Dmesg
<6> [223.600787] Console: switching to colour dummy device 80x25
<6> [223.601114] [IGT] kms_flip: executing
<7> [223.606701] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:402:DP-1]
<7> [223.606711] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:402:DP-1]
<7> [223.606863] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:402:DP-1] disconnected
<7> [223.607099] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:414:DP-2]
<7> [223.607102] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:414:DP-2]
<7> [223.607252] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:414:DP-2] disconnected
<7> [223.607496] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:422:DP-3]
<7> [223.607499] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:422:DP-3]
<7> [223.607620] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:422:DP-3] disconnected
<7> [223.607864] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:393:eDP-1]
<7> [223.607867] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:393:eDP-1]
<7> [223.608310] xe 0000:00:02.0: [drm:intel_dp_read_dsc_dpcd [xe]] DSC DPCD: 00 11 00 76 0b 01 01 80 00 01 06 66 08 00 00 00
<7> [223.608628] xe 0000:00:02.0: [drm:intel_dp_print_rates [xe]] source rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 675000, 810000
<7> [223.608740] xe 0000:00:02.0: [drm:intel_dp_print_rates [xe]] sink rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 810000
<7> [223.608849] xe 0000:00:02.0: [drm:intel_dp_print_rates [xe]] common rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 810000
<7> [223.608965] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:393:eDP-1] Supported Monitor Refresh rate range is 40 Hz - 120 Hz
<5> [223.608969] [drm] DisplayID checksum invalid, remainder is 248 (ignoring)
<5> [223.608971] [drm] DisplayID checksum invalid, remainder is 248 (ignoring)
<7> [223.608971] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:393:eDP-1] DisplayID extension version 0x13, primary use 0x00
<7> [223.608974] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:393:eDP-1] Assigning EDID-1.4 digital sink color depth as 10 bpc.
<5> [223.608976] [drm] DisplayID checksum invalid, remainder is 248 (ignoring)
<7> [223.608977] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:393:eDP-1] ELD monitor
<5> [223.608980] [drm] DisplayID checksum invalid, remainder is 248 (ignoring)
<7> [223.608981] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:393:eDP-1] ELD size 20, SAD count 0
<5> [223.608983] [drm] DisplayID checksum invalid, remainder is 248 (ignoring)
<7> [223.608993] xe 0000:00:02.0: [drm:intel_dp_set_edid [xe]] [CONNECTOR:393:eDP-1] VRR capable: yes
<7> [223.609145] xe 0000:00:02.0: [drm:intel_dp_set_edid [xe]] [CONNECTOR:393:eDP-1] DFP max bpc 0, max dotclock 0, TMDS clock 0-0, PCON Max FRL BW 0Gbps
<7> [223.609275] xe 0000:00:02.0: [drm:intel_dp_set_edid [xe]] [CONNECTOR:393:eDP-1] RGB->YcbCr conversion? no, YCbCr 4:2:0 allowed? yes, YCbCr 4:4:4->4:2:0 conversion? no
<5> [223.609635] [drm] DisplayID checksum invalid, remainder is 248 (ignoring)
<5> [223.609637] [drm] DisplayID checksum invalid, remainder is 248 (ignoring)
<5> [223.609639] [drm] DisplayID checksum invalid, remainder is 248 (ignoring)
<7> [223.609711] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:393:eDP-1] probed modes:
<7> [223.609715] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [223.609719] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2880x1800": 120 695310 2880 2928 2960 3040 1800 1803 1809 1906 0x40 0xa
<7> [223.609734] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:402:DP-1]
<7> [223.609738] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:402:DP-1]
<7> [223.609915] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:402:DP-1] disconnected
<7> [223.609926] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:414:DP-2]
<7> [223.609930] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:414:DP-2]
<7> [223.610103] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:414:DP-2] disconnected
<7> [223.610115] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:422:DP-3]
<7> [223.610118] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:422:DP-3]
<7> [223.610251] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:422:DP-3] disconnected
<6> [223.611048] [IGT] kms_flip: starting subtest blocking-wf_vblank
<6> [223.612371] [IGT] kms_flip: starting dynamic subtest A-eDP1
<7> [223.625602] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:430]
<7> [223.626170] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:432]
<7> [223.700942] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [223.701185] 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> [223.701478] 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> [223.701705] 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> [223.701929] 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> [223.702173] 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 6329869/8388608 link 421991/524288, found tu 0, data 0/0 link 0/0)
<7> [223.702382] 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> [223.702577] 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> [223.702771] 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> [223.702963] 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> [223.703194] 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> [223.703372] 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> [223.703536] 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> [223.703697] 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> [223.703859] 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> [223.704023] 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> [223.704178] 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> [223.704327] 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> [223.704471] 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> [223.704614] 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> [223.704752] 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> [223.704887] 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> [223.705032] 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> [223.705162] 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> [223.705287] 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> [223.705411] 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> [223.705531] 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> [223.705651] 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> [223.705768] 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> [223.705883] 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> [223.706007] 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> [223.706123] 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> [223.706237] 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> [223.706348] 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> [223.706458] 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> [223.706569] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [223.706676] 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> [223.706780] 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> [223.706884] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 432000, found 0)
<7> [223.706991] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [223.707121] xe 0000:00:02.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:151:pipe A] releasing DPLL 0
<7> [223.707282] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [223.707378] 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> [223.707505] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:35:plane 1A] ddb ( 0 - 4051) -> ( 0 - 0), size 4051 -> 0
<7> [223.707591] 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> [223.707664] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [223.707735] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] lines 1, 13, 13, 13, 13, 13, 0, 0, 0, 3, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [223.707803] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] blocks 24, 313, 313, 313, 313, 313, 0, 0, 38, 73, 87 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [223.707878] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] min_ddb 28, 346, 346, 346, 346, 346, 0, 0, 39, 82, 88 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [223.707972] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:151:pipe A] data rate 0 num active planes 0
<7> [223.708106] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 0 qgv_peak_bw: 38400
<7> [223.708219] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 0 qgv_peak_bw: 38400
<7> [223.708327] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [223.708424] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [223.708521] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 0
<7> [223.708618] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:151:pipe A] min cdclk: 173855 kHz -> 0 kHz
<7> [223.708732] xe 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 21732 kHz -> 0 kHz
<7> [223.708837] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [223.708941] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 153600 kHz, actual 153600 kHz
<7> [223.709064] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [223.709197] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:151:pipe A] enable: no [modeset]
<7> [223.709312] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:35:plane 1A] fb: [NOFB], visible: no
<7> [223.709411] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [223.709509] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [223.709596] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [223.709679] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [223.709760] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [223.709894] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [223.710001] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [223.710164] xe 0000:00:02.0: [drm:intel_psr_disable_locked [xe]] Disabling PSR2
<7> [223.710754] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [223.710863] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [223.913245] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:393:eDP-1] set backlight PWM = 0
<7> [223.913977] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe A
<7> [223.917384] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turn panel power off
<7> [223.917782] 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> [223.917822] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [223.918213] 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> [223.918344] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - short
<7> [223.969436] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [223.969811] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:151:pipe A]
<7> [223.969878] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [223.970155] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [223.970482] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - short
<7> [223.970843] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [223.971335] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disabling DPLL 0
<7> [223.971794] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [223.972342] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:392:DDI A/PHY A]
<7> [223.972693] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DDI TC1/PHY TC1]
<7> [223.973043] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST A]
<7> [223.973379] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:404:DP-MST B]
<7> [223.973692] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:405:DP-MST C]
<7> [223.974018] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DDI TC2/PHY TC2]
<7> [223.974381] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST A]
<7> [223.974699] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:416:DP-MST B]
<7> [223.975019] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:417:DP-MST C]
<7> [223.975314] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DDI TC3/PHY TC3]
<7> [223.975625] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST A]
<7> [223.975941] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:424:DP-MST B]
<7> [223.976308] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:425:DP-MST C]
<7> [223.976585] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:393:eDP-1]
<7> [223.976965] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [223.977320] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [223.977605] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [223.978154] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_A
<7> [223.978567] 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> [223.979066] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:151:pipe A]
<7> [223.979417] 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> [223.979835] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [223.980175] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=384
<7> [223.981090] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:270:pipe B]
<7> [223.981500] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:389:pipe C]
<7> [223.981821] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [223.981900] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:393:eDP-1]
<7> [223.982127] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CONNECTOR:393:eDP-1] Limiting target display pipe bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [223.982521] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:392:DDI A/PHY A][CRTC:151:pipe A] DP link limits: pixel clock 347710 kHz DSC off max lanes 4 max rate 810000 max pipe_bpp 30 min link_bpp 18.0000 max link_bpp 30.0000
<7> [223.982863] xe 0000:00:02.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 4 clock 432000 bpp input 30 compressed 0.0000 HDR no link rate required 1303913 available 1728000
<7> [223.983284] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [223.983612] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:392:DDI A/PHY A] [CRTC:151:pipe A]
<7> [223.983912] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [223.984242] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [223.984561] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [223.984836] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [223.985118] 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 0 data 0/0 link 0/0, found tu 64, data 6329869/8388608 link 421991/524288)
<7> [223.985363] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [223.985590] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [223.985815] 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 0, found 2880)
<7> [223.986060] 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 0, found 3040)
<7> [223.986288] 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 0, found 2880)
<7> [223.986487] 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 0, found 3040)
<7> [223.986682] 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 0, found 2928)
<7> [223.986873] 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 0, found 2960)
<7> [223.987115] 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 0, found 1800)
<7> [223.987310] 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 0, found 1800)
<7> [223.987486] 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 0, found 1803)
<7> [223.987656] 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 0, found 1809)
<7> [223.987820] 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 0, found 1906)
<7> [223.988006] 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 0, found 1906)
<7> [223.988164] 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 0, found 2880)
<7> [223.988317] 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 0, found 3040)
<7> [223.988466] 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 0, found 2880)
<7> [223.988613] 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 0, found 3040)
<7> [223.988778] 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 0, found 2928)
<7> [223.988942] 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 0, found 2960)
<7> [223.989100] 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 0, found 1800)
<7> [223.989252] 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 0, found 1800)
<7> [223.989407] 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 0, found 1803)
<7> [223.989546] 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 0, found 1809)
<7> [223.989680] 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 0, found 1906)
<7> [223.989807] 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 0, found 1906)
<7> [223.989933] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [223.990122] 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 0, found 2)
<7> [223.990240] 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 0, found 8)
<7> [223.990356] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [223.990469] 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 0, found 347710)
<7> [223.990578] 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 0, found 347710)
<7> [223.990685] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 0, found 432000)
<7> [223.990791] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [223.990937] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [223.991041] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:151:pipe A] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x0 -> 0x1
<7> [223.991149] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 4051), size 0 -> 4051
<7> [223.991221] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:145:cursor A] ddb ( 0 - 0) -> (4051 - 4096), size 0 -> 45
<7> [223.991292] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [223.991360] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 13, 13, 13, 13, 13, 0, 0, 0, 4, 0
<7> [223.991430] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 92, 297, 297, 297, 297, 297, 0, 0, 197, 92, 197
<7> [223.991498] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] 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> [223.991582] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:151:pipe A] data rate 1390840 num active planes 1
<7> [223.991688] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [223.991790] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [223.991887] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [223.991981] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [223.992100] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [223.992217] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:151:pipe A] min cdclk: 0 kHz -> 173855 kHz
<7> [223.992367] xe 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 0 kHz -> 21732 kHz
<7> [223.992467] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [223.992552] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [223.992634] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [223.992730] xe 0000:00:02.0: [drm:intel_find_dpll [xe]] [CRTC:151:pipe A] allocated DPLL 0
<7> [223.992809] xe 0000:00:02.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:151:pipe A] reserving DPLL 0
<7> [223.992888] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:151:pipe A] enable: yes [modeset]
<7> [223.992973] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [223.993080] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: A, pipe bpp: 30, dithering: 0
<7> [223.993187] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [223.993292] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [223.993393] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [223.993474] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [223.993551] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m_n: lanes: 4; data_m: 6329869, data_n: 8388608, link_m: 421991, link_n: 524288, tu: 64
<7> [223.993628] 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> [223.993705] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [223.993780] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [223.993855] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled
<7> [223.993931] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0
<7> [223.994009] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [223.994095] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 4, length 14
<7> [223.994192] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [223.994289] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [223.994371] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [223.994447] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [223.994520] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [223.994593] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [223.994665] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [223.994738] 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> [223.994812] 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> [223.994884] 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> [223.994957] 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> [223.995051] 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> [223.995158] 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> [223.995268] 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> [223.995361] 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> [223.995434] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 432000, pipe src: 2880x1800+0+0, pixel rate 347710, min cdclk 173855
<7> [223.995506] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [223.995579] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [223.995655] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [223.995730] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [223.995804] 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> [223.995879] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_hw_state: fracen: no,
<7> [223.995953] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] multiplier: 225, tx_clk_div: 1.
<7> [223.996048] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_rawhw_state:
<7> [223.996145] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] tx: 0x10, cmn: 0x21
<7> [223.996241] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1
<7> [223.996337] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [223.996418] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [223.996497] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [223.996580] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23
<7> [223.996663] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [223.996740] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [223.996812] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [223.996884] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [223.996955] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [223.997044] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [223.997137] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [223.997230] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [223.997322] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [223.997407] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [223.997478] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [223.997549] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [223.997621] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [223.997692] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:35:plane 1A] fb: [FB:430] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [223.997764] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [223.997834] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [223.997906] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [223.997998] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [223.998106] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [223.998216] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [223.998311] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [223.998693] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_A
<7> [223.998821] xe 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x1 (CLKGATE_DIS_DSSDSC=0xa8000000)
<7> [223.998915] 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> [223.999013] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [223.999116] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=480
<7> [223.999222] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:392:DDI A/PHY A]
<7> [223.999306] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DDI TC1/PHY TC1]
<7> [223.999371] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST A]
<7> [223.999436] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:404:DP-MST B]
<7> [223.999500] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:405:DP-MST C]
<7> [223.999566] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DDI TC2/PHY TC2]
<7> [223.999631] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST A]
<7> [223.999696] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:416:DP-MST B]
<7> [223.999759] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:417:DP-MST C]
<7> [223.999821] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DDI TC3/PHY TC3]
<7> [223.999882] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST A]
<7> [223.999942] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:424:DP-MST B]
<7> [224.000016] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:425:DP-MST C]
<7> [224.000116] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [224.000301] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [224.000363] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 8 (mbus joined: yes)
<7> [224.000466] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [224.001783] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:151:pipe A]
<7> [224.001860] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enabling DPLL 0
<7> [224.003805] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turn panel power on
<7> [224.003872] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 wait for panel power cycle (466 ms remaining)
<7> [224.006587] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [224.006738] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - long
<7> [224.006829] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [224.006934] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [224.473220] 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> [224.514364] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [224.514849] 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> [224.515370] 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> [224.551524] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [224.551970] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - long
<7> [224.552361] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [224.552759] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [224.641085] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port F/TC#3: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [224.641096] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port E/TC#2: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [224.641225] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port D/TC#1: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [224.725557] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [224.725962] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turning VDD on
<7> [224.726419] 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> [224.728556] 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> [224.730153] 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> [224.731062] 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 05
<7> [224.732285] 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> [224.733681] 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> [224.734818] 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> [224.735222] 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> [224.736712] 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> [224.737118] 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 = 432000, lane count = 4
<7> [224.737965] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe A
<7> [224.738631] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [224.739075] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe A
<7> [224.739530] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:393:eDP-1] set backlight PWM = 192000
<7> [224.755386] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR2
<7> [224.757218] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:393:eDP-1]
<7> [224.757666] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:151:pipe A]
<7> [224.778059] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [224.778706] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [224.779292] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [225.043820] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [225.044476] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [225.073033] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [225.073606] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [225.074132] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [227.265144] 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> [227.265501] 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> [238.277824] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [238.278471] 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> [238.279310] 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> [238.280040] 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> [238.280864] 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> [238.281628] 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 6329869/8388608 link 421991/524288, found tu 0, data 0/0 link 0/0)
<7> [238.282287] 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> [238.282959] 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> [238.283636] 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> [238.284280] 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> [238.284893] 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> [238.285545] 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> [238.286250] 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> [238.286834] 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> [238.287504] 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> [238.288085] 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> [238.288725] 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> [238.289268] 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> [238.289757] 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> [238.290247] 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> [238.290822] 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> [238.291383] 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> [238.291871] 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> [238.292388] 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> [238.292840] 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> [238.293262] 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> [238.293725] 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> [238.294116] 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> [238.294501] 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> [238.294835] 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> [238.295142] 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> [238.295476] 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> [238.295761] 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> [238.296044] 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> [238.296316] 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> [238.296576] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [238.296822] 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> [238.297062] 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> [238.297329] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 432000, found 0)
<7> [238.297559] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [238.297774] xe 0000:00:02.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:151:pipe A] releasing DPLL 0
<7> [238.298102] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [238.298275] 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> [238.298517] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:35:plane 1A] ddb ( 0 - 4051) -> ( 0 - 0), size 4051 -> 0
<7> [238.298668] 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> [238.298806] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [238.298943] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] lines 4, 13, 13, 13, 13, 13, 0, 0, 0, 4, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [238.299077] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] blocks 92, 297, 297, 297, 297, 297, 0, 0, 197, 92, 197 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [238.299223] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] 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> [238.299407] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:151:pipe A] data rate 0 num active planes 0
<7> [238.299597] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 0 qgv_peak_bw: 38400
<7> [238.299778] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 0 qgv_peak_bw: 38400
<7> [238.299950] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [238.300116] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [238.300274] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 0
<7> [238.300459] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:151:pipe A] min cdclk: 173855 kHz -> 0 kHz
<7> [238.300646] xe 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 21732 kHz -> 0 kHz
<7> [238.300795] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [238.300938] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 153600 kHz, actual 153600 kHz
<7> [238.301078] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [238.301241] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:151:pipe A] enable: no [modeset]
<7> [238.301409] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:35:plane 1A] fb: [NOFB], visible: no
<7> [238.301558] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [238.301687] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [238.301811] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [238.301930] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [238.302063] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [238.302263] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [238.302389] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [238.302552] xe 0000:00:02.0: [drm:intel_psr_disable_locked [xe]] Disabling PSR2
<7> [238.302737] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turning VDD on
<7> [238.302852] 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> [238.303480] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [238.303607] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [238.510444] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:393:eDP-1] set backlight PWM = 0
<7> [238.510966] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe A
<7> [238.522153] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turn panel power off
<7> [238.522562] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [238.522734] 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> [238.523200] 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> [238.523221] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - short
<7> [238.574514] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [238.574957] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:151:pipe A]
<7> [238.575070] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [238.575010] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [238.575464] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - short
<7> [238.575918] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [238.576468] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disabling DPLL 0
<7> [238.576976] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [238.577567] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:392:DDI A/PHY A]
<7> [238.578011] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DDI TC1/PHY TC1]
<7> [238.578474] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST A]
<7> [238.578897] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:404:DP-MST B]
<7> [238.579333] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:405:DP-MST C]
<7> [238.579744] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DDI TC2/PHY TC2]
<7> [238.580160] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST A]
<7> [238.580578] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:416:DP-MST B]
<7> [238.580989] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:417:DP-MST C]
<7> [238.581366] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DDI TC3/PHY TC3]
<7> [238.581731] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST A]
<7> [238.582174] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:424:DP-MST B]
<7> [238.582533] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:425:DP-MST C]
<7> [238.582896] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:393:eDP-1]
<7> [238.583432] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [238.583775] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [238.584174] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [238.584798] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_A
<7> [238.585362] 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> [238.585989] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:151:pipe A]
<7> [238.586445] 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> [238.586982] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [238.587318] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=384
<7> [238.588618] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:270:pipe B]
<7> [238.589059] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:389:pipe C]
<7> [238.589358] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [238.589425] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:393:eDP-1]
<7> [238.589599] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CONNECTOR:393:eDP-1] Limiting target display pipe bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [238.589964] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:392:DDI A/PHY A][CRTC:151:pipe A] DP link limits: pixel clock 347710 kHz DSC off max lanes 4 max rate 810000 max pipe_bpp 30 min link_bpp 18.0000 max link_bpp 30.0000
<7> [238.590337] xe 0000:00:02.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 4 clock 432000 bpp input 30 compressed 0.0000 HDR no link rate required 1303913 available 1728000
<7> [238.590651] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [238.590958] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:392:DDI A/PHY A] [CRTC:151:pipe A]
<7> [238.591294] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [238.591576] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [238.591838] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [238.592118] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [238.592372] 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 0 data 0/0 link 0/0, found tu 64, data 6329869/8388608 link 421991/524288)
<7> [238.592600] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [238.592829] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [238.593090] 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 0, found 2880)
<7> [238.593335] 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 0, found 3040)
<7> [238.593553] 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 0, found 2880)
<7> [238.593739] 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 0, found 3040)
<7> [238.593913] 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 0, found 2928)
<7> [238.594112] 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 0, found 2960)
<7> [238.594280] 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 0, found 1800)
<7> [238.594471] 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 0, found 1800)
<7> [238.594625] 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 0, found 1803)
<7> [238.594777] 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 0, found 1809)
<7> [238.594927] 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 0, found 1906)
<7> [238.595096] 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 0, found 1906)
<7> [238.595240] 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 0, found 2880)
<7> [238.595380] 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 0, found 3040)
<7> [238.595516] 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 0, found 2880)
<7> [238.595644] 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 0, found 3040)
<7> [238.595772] 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 0, found 2928)
<7> [238.595910] 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 0, found 2960)
<7> [238.596066] 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 0, found 1800)
<7> [238.596192] 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 0, found 1800)
<7> [238.596318] 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 0, found 1803)
<7> [238.596442] 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 0, found 1809)
<7> [238.596561] 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 0, found 1906)
<7> [238.596676] 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 0, found 1906)
<7> [238.596790] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [238.596918] 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 0, found 2)
<7> [238.597031] 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 0, found 8)
<7> [238.597226] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [238.597329] 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 0, found 347710)
<7> [238.597429] 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 0, found 347710)
<7> [238.597538] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 0, found 432000)
<7> [238.597650] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [238.597801] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [238.597881] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:151:pipe A] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x0 -> 0x1
<7> [238.597978] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 4051), size 0 -> 4051
<7> [238.598055] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:145:cursor A] ddb ( 0 - 0) -> (4051 - 4096), size 0 -> 45
<7> [238.598121] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [238.598186] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 13, 13, 13, 13, 13, 0, 0, 0, 4, 0
<7> [238.598249] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 92, 297, 297, 297, 297, 297, 0, 0, 197, 92, 197
<7> [238.598310] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] 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> [238.598385] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:151:pipe A] data rate 1390840 num active planes 1
<7> [238.598480] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [238.598570] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [238.598656] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [238.598743] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [238.598826] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [238.598913] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:151:pipe A] min cdclk: 0 kHz -> 173855 kHz
<7> [238.599020] xe 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 0 kHz -> 21732 kHz
<7> [238.599134] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [238.599224] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [238.599314] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [238.599408] xe 0000:00:02.0: [drm:intel_find_dpll [xe]] [CRTC:151:pipe A] allocated DPLL 0
<7> [238.599484] xe 0000:00:02.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:151:pipe A] reserving DPLL 0
<7> [238.599570] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:151:pipe A] enable: yes [modeset]
<7> [238.599656] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [238.599739] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: A, pipe bpp: 30, dithering: 0
<7> [238.599819] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [238.599899] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [238.599978] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [238.600058] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [238.600154] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m_n: lanes: 4; data_m: 6329869, data_n: 8388608, link_m: 421991, link_n: 524288, tu: 64
<7> [238.600236] 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> [238.600314] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [238.600390] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [238.600465] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled
<7> [238.600541] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0
<7> [238.600616] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [238.600691] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 4, length 14
<7> [238.600770] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [238.600843] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [238.600917] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [238.600991] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [238.601080] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [238.601171] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [238.601255] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [238.601337] 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> [238.601410] 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> [238.601483] 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> [238.601556] 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> [238.601630] 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> [238.601703] 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> [238.601776] 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> [238.601849] 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> [238.601922] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 432000, pipe src: 2880x1800+0+0, pixel rate 347710, min cdclk 173855
<7> [238.601994] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [238.602066] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [238.602157] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [238.602250] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [238.602331] 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> [238.602406] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_hw_state: fracen: no,
<7> [238.602479] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] multiplier: 225, tx_clk_div: 1.
<7> [238.602553] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_rawhw_state:
<7> [238.602627] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] tx: 0x10, cmn: 0x21
<7> [238.602701] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1
<7> [238.602776] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [238.602849] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [238.602922] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [238.602999] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23
<7> [238.603080] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [238.603175] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [238.603262] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [238.603341] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [238.603412] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [238.603483] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [238.603554] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [238.603623] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [238.603693] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [238.603763] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [238.603833] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [238.603902] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [238.603972] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [238.604041] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:35:plane 1A] fb: [FB:430] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [238.604122] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [238.604193] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [238.604268] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [238.604339] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [238.604409] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [238.604481] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [238.604551] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [238.604915] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_A
<7> [238.605058] xe 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x1 (CLKGATE_DIS_DSSDSC=0xa8000000)
<7> [238.605189] 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> [238.605295] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [238.605381] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=480
<7> [238.605464] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:392:DDI A/PHY A]
<7> [238.605533] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DDI TC1/PHY TC1]
<7> [238.605600] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST A]
<7> [238.605665] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:404:DP-MST B]
<7> [238.605728] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:405:DP-MST C]
<7> [238.605792] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DDI TC2/PHY TC2]
<7> [238.605855] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST A]
<7> [238.605918] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:416:DP-MST B]
<7> [238.605981] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:417:DP-MST C]
<7> [238.606042] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DDI TC3/PHY TC3]
<7> [238.606103] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST A]
<7> [238.606180] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:424:DP-MST B]
<7> [238.606253] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:425:DP-MST C]
<7> [238.606347] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [238.606507] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [238.606569] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 8 (mbus joined: yes)
<7> [238.606674] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [238.608063] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:151:pipe A]
<7> [238.608204] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enabling DPLL 0
<7> [238.610169] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turn panel power on
<7> [238.610254] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 wait for panel power cycle (465 ms remaining)
<7> [238.611319] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [238.611434] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - long
<7> [238.611520] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [238.611610] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [239.083579] 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> [239.114552] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [239.114911] 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> [239.115224] 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> [239.151563] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [239.151909] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - long
<7> [239.152228] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [239.152568] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [239.321588] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [239.322039] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turning VDD on
<7> [239.322498] 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> [239.324672] 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> [239.326362] 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> [239.327487] 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 05
<7> [239.328943] 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> [239.330539] 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> [239.331841] 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> [239.332395] 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> [239.334360] 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> [239.335022] 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 = 432000, lane count = 4
<7> [239.336116] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe A
<7> [239.336883] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [239.337530] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe A
<7> [239.338149] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:393:eDP-1] set backlight PWM = 192000
<7> [239.353750] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR2
<7> [239.355656] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:393:eDP-1]
<7> [239.356132] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:151:pipe A]
<7> [239.376861] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [239.377436] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [239.378001] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [239.639267] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [239.639721] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [239.673164] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [239.673742] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [239.674300] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [241.927306] 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> [241.928013] 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> [250.062895] [IGT] kms_flip: finished subtest A-eDP1, FAIL
Created at 2026-03-24 19:59:13