Results for igt@kms_flip@flip-vs-absolute-wf_vblank@a-edp1

Result: Fail

integration-manifest git-log-oneline i915_display_info15 igt_runner15 runtimes15 results15.json results15-i915-load.json guc_logs15.tar i915_display_info_post_exec15 boot15 dmesg15

DetailValue
Duration 21.19 seconds
Hostname 
shard-mtlp-4
Igt-Version 
IGT-Version: 2.3-ge61a8b4ea (x86_64) (Linux: 6.19.0-rc5-CI_DRM_17807-gfb2e525015f1+ x86_64)
Out 
Using IGT_SRANDOM=1768244951 for randomisation
Opened device: /dev/dri/card0
Using monotonic timestamps
Starting subtest: flip-vs-absolute-wf_vblank
Starting dynamic subtest: A-eDP1
  2880x1800: 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x48 0xa 
Expected frametime: 11111us; measured 11111.2us +- 2.463us accuracy 0.07%
Event flip: expected 682, counted 700, passrate = 18.57%, encoder type 2
Event vblank: expected 682, counted 700, passrate = 17.14%, encoder type 2
Expected frametime: 11111us; measured 10986.3us +- 2.442us accuracy 0.07%
vblank interval differs from modeline! expected 11111.1us, measured 10986us +- 2.442us, difference 124.8us (51.1 sigma)
Event flip: expected 682, counted 680, passrate = 61.76%, encoder type 2
Event vblank: expected 682, counted 680, passrate = 60.29%, 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:1889 run_test()
  #3 ../tests/kms_flip.c:2122 __igt_unique____real_main2045()
  #4 ../tests/kms_flip.c:2045 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest A-eDP1: FAIL (21.187s)
Err 
Starting subtest: flip-vs-absolute-wf_vblank
Starting dynamic subtest: A-eDP1
(kms_flip:2806) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1709:
(kms_flip:2806) CRITICAL: Failed assertion: !retried
Dynamic subtest A-eDP1 failed.
**** DEBUG ****
(kms_flip:2806) DEBUG: flip ts/seq: last 440.790070/17188, current 440.900208/17198: elapsed=110145.0us expected=109863.1us +- 549.3us, error 0.3%
(kms_flip:2806) DEBUG: name = vblank
last_ts = 440.889191
last_received_ts = 440.889435
last_seq = 17197
current_ts = 440.999329
current_received_ts = 440.999451
current_seq = 17207
count = 21
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 440.889191/17197, current 440.999329/17207: elapsed=110138.0us expected=109863.1us +- 549.3us, error 0.3%
(kms_flip:2806) DEBUG: name = flip
last_ts = 440.900208
last_received_ts = 440.900085
last_seq = 17198
current_ts = 441.010345
current_received_ts = 441.010132
current_seq = 17208
count = 22
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 440.900208/17198, current 441.010345/17208: elapsed=110138.0us expected=109863.1us +- 549.3us, error 0.3%
(kms_flip:2806) DEBUG: name = vblank
last_ts = 440.999329
last_received_ts = 440.999451
last_seq = 17207
current_ts = 441.109528
current_received_ts = 441.109772
current_seq = 17217
count = 22
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 440.999329/17207, current 441.109528/17217: elapsed=110199.0us expected=109863.1us +- 549.3us, error 0.3%
(kms_flip:2806) DEBUG: name = flip
last_ts = 441.010345
last_received_ts = 441.010132
last_seq = 17208
current_ts = 441.120575
current_received_ts = 441.120422
current_seq = 17218
count = 23
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 441.010345/17208, current 441.120575/17218: elapsed=110211.0us expected=109863.1us +- 549.3us, error 0.3%
(kms_flip:2806) DEBUG: name = vblank
last_ts = 441.109528
last_received_ts = 441.109772
last_seq = 17217
current_ts = 441.219788
current_received_ts = 441.220123
current_seq = 17227
count = 23
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 441.109528/17217, current 441.219788/17227: elapsed=110256.0us expected=109863.1us +- 549.3us, error 0.4%
(kms_flip:2806) DEBUG: name = flip
last_ts = 441.120575
last_received_ts = 441.120422
last_seq = 17218
current_ts = 441.230835
current_received_ts = 441.230591
current_seq = 17228
count = 24
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 441.120575/17218, current 441.230835/17228: elapsed=110259.0us expected=109863.1us +- 549.3us, error 0.4%
(kms_flip:2806) DEBUG: name = vblank
last_ts = 441.219788
last_received_ts = 441.220123
last_seq = 17227
current_ts = 441.330048
current_received_ts = 441.330353
current_seq = 17237
count = 24
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 441.219788/17227, current 441.330048/17237: elapsed=110256.0us expected=109863.1us +- 549.3us, error 0.4%
(kms_flip:2806) DEBUG: name = flip
last_ts = 441.230835
last_received_ts = 441.230591
last_seq = 17228
current_ts = 441.341064
current_received_ts = 441.340820
current_seq = 17238
count = 25
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 441.230835/17228, current 441.341064/17238: elapsed=110253.0us expected=109863.1us +- 549.3us, error 0.4%
(kms_flip:2806) DEBUG: name = vblank
last_ts = 441.330048
last_received_ts = 441.330353
last_seq = 17237
current_ts = 441.440308
current_received_ts = 441.440399
current_seq = 17247
count = 25
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 441.330048/17237, current 441.440308/17247: elapsed=110260.0us expected=109863.1us +- 549.3us, error 0.4%
(kms_flip:2806) DEBUG: name = flip
last_ts = 441.341064
last_received_ts = 441.340820
last_seq = 17238
current_ts = 441.451324
current_received_ts = 441.451111
current_seq = 17248
count = 26
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 441.341064/17238, current 441.451324/17248: elapsed=110256.0us expected=109863.1us +- 549.3us, error 0.4%
(kms_flip:2806) DEBUG: name = vblank
last_ts = 441.440308
last_received_ts = 441.440399
last_seq = 17247
current_ts = 441.550568
current_received_ts = 441.550781
current_seq = 17257
count = 26
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 441.440308/17247, current 441.550568/17257: elapsed=110257.0us expected=109863.1us +- 549.3us, error 0.4%
(kms_flip:2806) DEBUG: name = flip
last_ts = 441.451324
last_received_ts = 441.451111
last_seq = 17248
current_ts = 441.561584
current_received_ts = 441.561310
current_seq = 17258
count = 27
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 441.451324/17248, current 441.561584/17258: elapsed=110260.0us expected=109863.1us +- 549.3us, error 0.4%
(kms_flip:2806) DEBUG: name = vblank
last_ts = 441.550568
last_received_ts = 441.550781
last_seq = 17257
current_ts = 441.660828
current_received_ts = 441.660919
current_seq = 17267
count = 27
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 441.550568/17257, current 441.660828/17267: elapsed=110254.0us expected=109863.1us +- 549.3us, error 0.4%
(kms_flip:2806) DEBUG: name = flip
last_ts = 441.561584
last_received_ts = 441.561310
last_seq = 17258
current_ts = 441.671844
current_received_ts = 441.671570
current_seq = 17268
count = 28
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 441.561584/17258, current 441.671844/17268: elapsed=110254.0us expected=109863.1us +- 549.3us, error 0.4%
(kms_flip:2806) DEBUG: name = vblank
last_ts = 441.660828
last_received_ts = 441.660919
last_seq = 17267
current_ts = 441.771088
current_received_ts = 441.771301
current_seq = 17277
count = 28
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 441.660828/17267, current 441.771088/17277: elapsed=110257.0us expected=109863.1us +- 549.3us, error 0.4%
(kms_flip:2806) DEBUG: name = flip
last_ts = 441.671844
last_received_ts = 441.671570
last_seq = 17268
current_ts = 441.782104
current_received_ts = 441.781952
current_seq = 17278
count = 29
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 441.671844/17268, current 441.782104/17278: elapsed=110259.0us expected=109863.1us +- 549.3us, error 0.4%
(kms_flip:2806) DEBUG: name = vblank
last_ts = 441.771088
last_received_ts = 441.771301
last_seq = 17277
current_ts = 441.881348
current_received_ts = 441.881592
current_seq = 17287
count = 29
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 441.771088/17277, current 441.881348/17287: elapsed=110257.0us expected=109863.1us +- 549.3us, error 0.4%
(kms_flip:2806) DEBUG: name = flip
last_ts = 441.782104
last_received_ts = 441.781952
last_seq = 17278
current_ts = 441.892365
current_received_ts = 441.892090
current_seq = 17288
count = 30
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 441.782104/17278, current 441.892365/17288: elapsed=110256.0us expected=109863.1us +- 549.3us, error 0.4%
(kms_flip:2806) DEBUG: name = vblank
last_ts = 441.881348
last_received_ts = 441.881592
last_seq = 17287
current_ts = 441.991608
current_received_ts = 441.991852
current_seq = 17297
count = 30
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 441.881348/17287, current 441.991608/17297: elapsed=110257.0us expected=109863.1us +- 549.3us, error 0.4%
(kms_flip:2806) DEBUG: name = flip
last_ts = 441.892365
last_received_ts = 441.892090
last_seq = 17288
current_ts = 442.002625
current_received_ts = 442.002472
current_seq = 17298
count = 31
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 441.892365/17288, current 442.002625/17298: elapsed=110252.0us expected=109863.1us +- 549.3us, error 0.4%
(kms_flip:2806) DEBUG: name = vblank
last_ts = 441.991608
last_received_ts = 441.991852
last_seq = 17297
current_ts = 442.101898
current_received_ts = 442.101624
current_seq = 17307
count = 31
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 441.991608/17297, current 442.101898/17307: elapsed=110299.0us expected=109863.1us +- 549.3us, error 0.4%
(kms_flip:2806) DEBUG: name = flip
last_ts = 442.002625
last_received_ts = 442.002472
last_seq = 17298
current_ts = 442.112915
current_received_ts = 442.112579
current_seq = 17308
count = 32
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 442.002625/17298, current 442.112915/17308: elapsed=110313.0us expected=109863.1us +- 549.3us, error 0.4%
(kms_flip:2806) DEBUG: name = vblank
last_ts = 442.101898
last_received_ts = 442.101624
last_seq = 17307
current_ts = 442.212250
current_received_ts = 442.212036
current_seq = 17317
count = 32
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 442.101898/17307, current 442.212250/17317: elapsed=110354.0us expected=109863.1us +- 549.3us, error 0.4%
(kms_flip:2806) DEBUG: name = flip
last_ts = 442.112915
last_received_ts = 442.112579
last_seq = 17308
current_ts = 442.223297
current_received_ts = 442.222931
current_seq = 17318
count = 33
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 442.112915/17308, current 442.223297/17318: elapsed=110357.0us expected=109863.1us +- 549.3us, error 0.4%
(kms_flip:2806) DEBUG: name = vblank
last_ts = 442.212250
last_received_ts = 442.212036
last_seq = 17317
current_ts = 442.322601
current_received_ts = 442.322845
current_seq = 17327
count = 33
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 442.212250/17317, current 442.322601/17327: elapsed=110357.0us expected=109863.1us +- 549.3us, error 0.4%
(kms_flip:2806) DEBUG: name = flip
last_ts = 442.223297
last_received_ts = 442.222931
last_seq = 17318
current_ts = 442.333649
current_received_ts = 442.333527
current_seq = 17328
count = 34
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 442.223297/17318, current 442.333649/17328: elapsed=110356.0us expected=109863.1us +- 549.3us, error 0.4%
(kms_flip:2806) DEBUG: name = vblank
last_ts = 442.322601
last_received_ts = 442.322845
last_seq = 17327
current_ts = 442.432953
current_received_ts = 442.433197
current_seq = 17337
count = 34
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 442.322601/17327, current 442.432953/17337: elapsed=110357.0us expected=109863.1us +- 549.3us, error 0.4%
(kms_flip:2806) DEBUG: name = flip
last_ts = 442.333649
last_received_ts = 442.333527
last_seq = 17328
current_ts = 442.444000
current_received_ts = 442.443726
current_seq = 17338
count = 35
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 442.333649/17328, current 442.444000/17338: elapsed=110352.0us expected=109863.1us +- 549.3us, error 0.4%
(kms_flip:2806) DEBUG: name = vblank
last_ts = 442.432953
last_received_ts = 442.433197
last_seq = 17337
current_ts = 442.543304
current_received_ts = 442.543152
current_seq = 17347
count = 35
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 442.432953/17337, current 442.543304/17347: elapsed=110359.0us expected=109863.1us +- 549.3us, error 0.5%
(kms_flip:2806) DEBUG: name = flip
last_ts = 442.444000
last_received_ts = 442.443726
last_seq = 17338
current_ts = 442.554352
current_received_ts = 442.553986
current_seq = 17348
count = 36
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 442.444000/17338, current 442.554352/17348: elapsed=110358.0us expected=109863.1us +- 549.3us, error 0.5%
(kms_flip:2806) DEBUG: name = vblank
last_ts = 442.543304
last_received_ts = 442.543152
last_seq = 17347
current_ts = 442.653656
current_received_ts = 442.653900
current_seq = 17357
count = 36
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 442.543304/17347, current 442.653656/17357: elapsed=110352.0us expected=109863.1us +- 549.3us, error 0.4%
(kms_flip:2806) DEBUG: name = flip
last_ts = 442.554352
last_received_ts = 442.553986
last_seq = 17348
current_ts = 442.664703
current_received_ts = 442.664520
current_seq = 17358
count = 37
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 442.554352/17348, current 442.664703/17358: elapsed=110356.0us expected=109863.1us +- 549.3us, error 0.4%
(kms_flip:2806) DEBUG: name = vblank
last_ts = 442.653656
last_received_ts = 442.653900
last_seq = 17357
current_ts = 442.764038
current_received_ts = 442.764160
current_seq = 17367
count = 37
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 442.653656/17357, current 442.764038/17367: elapsed=110359.0us expected=109863.1us +- 549.3us, error 0.5%
(kms_flip:2806) DEBUG: name = flip
last_ts = 442.664703
last_received_ts = 442.664520
last_seq = 17358
current_ts = 442.775055
current_received_ts = 442.774719
current_seq = 17368
count = 38
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 442.664703/17358, current 442.775055/17368: elapsed=110358.0us expected=109863.1us +- 549.3us, error 0.5%
(kms_flip:2806) DEBUG: name = vblank
last_ts = 442.764038
last_received_ts = 442.764160
last_seq = 17367
current_ts = 442.874390
current_received_ts = 442.874481
current_seq = 17377
count = 38
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 442.764038/17367, current 442.874390/17377: elapsed=110357.0us expected=109863.1us +- 549.3us, error 0.4%
(kms_flip:2806) DEBUG: name = flip
last_ts = 442.775055
last_received_ts = 442.774719
last_seq = 17368
current_ts = 442.885437
current_received_ts = 442.885101
current_seq = 17378
count = 39
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 442.775055/17368, current 442.885437/17378: elapsed=110356.0us expected=109863.1us +- 549.3us, error 0.4%
(kms_flip:2806) DEBUG: name = vblank
last_ts = 442.874390
last_received_ts = 442.874481
last_seq = 17377
current_ts = 442.984741
current_received_ts = 442.984711
current_seq = 17387
count = 39
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 442.874390/17377, current 442.984741/17387: elapsed=110354.0us expected=109863.1us +- 549.3us, error 0.4%
(kms_flip:2806) DEBUG: name = flip
last_ts = 442.885437
last_received_ts = 442.885101
last_seq = 17378
current_ts = 442.995789
current_received_ts = 442.995422
current_seq = 17388
count = 40
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 442.885437/17378, current 442.995789/17388: elapsed=110358.0us expected=109863.1us +- 549.3us, error 0.5%
(kms_flip:2806) DEBUG: name = vblank
last_ts = 442.984741
last_received_ts = 442.984711
last_seq = 17387
current_ts = 443.095123
current_received_ts = 443.095428
current_seq = 17397
count = 40
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 442.984741/17387, current 443.095123/17397: elapsed=110382.0us expected=109863.1us +- 549.3us, error 0.5%
(kms_flip:2806) DEBUG: name = flip
last_ts = 442.995789
last_received_ts = 442.995422
last_seq = 17388
current_ts = 443.106171
current_received_ts = 443.105927
current_seq = 17398
count = 41
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 442.995789/17388, current 443.106171/17398: elapsed=110387.0us expected=109863.1us +- 549.3us, error 0.5%
(kms_flip:2806) DEBUG: name = vblank
last_ts = 443.095123
last_received_ts = 443.095428
last_seq = 17397
current_ts = 443.205566
current_received_ts = 443.205780
current_seq = 17407
count = 41
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 443.095123/17397, current 443.205566/17407: elapsed=110444.0us expected=109863.1us +- 549.3us, error 0.5%
(kms_flip:2806) DEBUG: inconsistent vblank ts/seq: last 443.095123/17397, current 443.205566/17407: elapsed=110444.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = flip
last_ts = 443.106171
last_received_ts = 443.105927
last_seq = 17398
current_ts = 443.216614
current_received_ts = 443.216248
current_seq = 17408
count = 42
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 443.106171/17398, current 443.216614/17408: elapsed=110448.0us expected=109863.1us +- 549.3us, error 0.5%
(kms_flip:2806) DEBUG: inconsistent flip ts/seq: last 443.106171/17398, current 443.216614/17408: elapsed=110448.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = vblank
last_ts = 443.205566
last_received_ts = 443.205780
last_seq = 17407
current_ts = 443.316010
current_received_ts = 443.316223
current_seq = 17417
count = 42
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 443.205566/17407, current 443.316010/17417: elapsed=110446.0us expected=109863.1us +- 549.3us, error 0.5%
(kms_flip:2806) DEBUG: inconsistent vblank ts/seq: last 443.205566/17407, current 443.316010/17417: elapsed=110446.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = flip
last_ts = 443.216614
last_received_ts = 443.216248
last_seq = 17408
current_ts = 443.327057
current_received_ts = 443.326813
current_seq = 17418
count = 43
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 443.216614/17408, current 443.327057/17418: elapsed=110443.0us expected=109863.1us +- 549.3us, error 0.5%
(kms_flip:2806) DEBUG: inconsistent flip ts/seq: last 443.216614/17408, current 443.327057/17418: elapsed=110443.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = vblank
last_ts = 443.316010
last_received_ts = 443.316223
last_seq = 17417
current_ts = 443.426453
current_received_ts = 443.426697
current_seq = 17427
count = 43
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 443.316010/17417, current 443.426453/17427: elapsed=110441.0us expected=109863.1us +- 549.3us, error 0.5%
(kms_flip:2806) DEBUG: inconsistent vblank ts/seq: last 443.316010/17417, current 443.426453/17427: elapsed=110441.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = flip
last_ts = 443.327057
last_received_ts = 443.326813
last_seq = 17418
current_ts = 443.437500
current_received_ts = 443.437134
current_seq = 17428
count = 44
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 443.327057/17418, current 443.437500/17428: elapsed=110444.0us expected=109863.1us +- 549.3us, error 0.5%
(kms_flip:2806) DEBUG: inconsistent flip ts/seq: last 443.327057/17418, current 443.437500/17428: elapsed=110444.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = vblank
last_ts = 443.426453
last_received_ts = 443.426697
last_seq = 17427
current_ts = 443.536896
current_received_ts = 443.537201
current_seq = 17437
count = 44
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 443.426453/17427, current 443.536896/17437: elapsed=110447.0us expected=109863.1us +- 549.3us, error 0.5%
(kms_flip:2806) DEBUG: inconsistent vblank ts/seq: last 443.426453/17427, current 443.536896/17437: elapsed=110447.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = flip
last_ts = 443.437500
last_received_ts = 443.437134
last_seq = 17428
current_ts = 443.547943
current_received_ts = 443.547607
current_seq = 17438
count = 45
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 443.437500/17428, current 443.547943/17438: elapsed=110446.0us expected=109863.1us +- 549.3us, error 0.5%
(kms_flip:2806) DEBUG: inconsistent flip ts/seq: last 443.437500/17428, current 443.547943/17438: elapsed=110446.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = vblank
last_ts = 443.536896
last_received_ts = 443.537201
last_seq = 17437
current_ts = 443.647339
current_received_ts = 443.647491
current_seq = 17447
count = 45
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 443.536896/17437, current 443.647339/17447: elapsed=110442.0us expected=109863.1us +- 549.3us, error 0.5%
(kms_flip:2806) DEBUG: inconsistent vblank ts/seq: last 443.536896/17437, current 443.647339/17447: elapsed=110442.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = flip
last_ts = 443.547943
last_received_ts = 443.547607
last_seq = 17438
current_ts = 443.658386
current_received_ts = 443.658081
current_seq = 17448
count = 46
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 443.547943/17438, current 443.658386/17448: elapsed=110444.0us expected=109863.1us +- 549.3us, error 0.5%
(kms_flip:2806) DEBUG: inconsistent flip ts/seq: last 443.547943/17438, current 443.658386/17448: elapsed=110444.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = vblank
last_ts = 443.647339
last_received_ts = 443.647491
last_seq = 17447
current_ts = 443.757782
current_received_ts = 443.758087
current_seq = 17457
count = 46
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 443.647339/17447, current 443.757782/17457: elapsed=110447.0us expected=109863.1us +- 549.3us, error 0.5%
(kms_flip:2806) DEBUG: inconsistent vblank ts/seq: last 443.647339/17447, current 443.757782/17457: elapsed=110447.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = flip
last_ts = 443.658386
last_received_ts = 443.658081
last_seq = 17448
current_ts = 443.768829
current_received_ts = 443.768494
current_seq = 17458
count = 47
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 443.658386/17448, current 443.768829/17458: elapsed=110444.0us expected=109863.1us +- 549.3us, error 0.5%
(kms_flip:2806) DEBUG: inconsistent flip ts/seq: last 443.658386/17448, current 443.768829/17458: elapsed=110444.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = vblank
last_ts = 443.757782
last_received_ts = 443.758087
last_seq = 17457
current_ts = 443.868225
current_received_ts = 443.868500
current_seq = 17467
count = 47
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 443.757782/17457, current 443.868225/17467: elapsed=110445.0us expected=109863.1us +- 549.3us, error 0.5%
(kms_flip:2806) DEBUG: inconsistent vblank ts/seq: last 443.757782/17457, current 443.868225/17467: elapsed=110445.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = flip
last_ts = 443.768829
last_received_ts = 443.768494
last_seq = 17458
current_ts = 443.879272
current_received_ts = 443.878967
current_seq = 17468
count = 48
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 443.768829/17458, current 443.879272/17468: elapsed=110439.0us expected=109863.1us +- 549.3us, error 0.5%
(kms_flip:2806) DEBUG: inconsistent flip ts/seq: last 443.768829/17458, current 443.879272/17468: elapsed=110439.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = vblank
last_ts = 443.868225
last_received_ts = 443.868500
last_seq = 17467
current_ts = 443.978668
current_received_ts = 443.978912
current_seq = 17477
count = 48
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 443.868225/17467, current 443.978668/17477: elapsed=110439.0us expected=109863.1us +- 549.3us, error 0.5%
(kms_flip:2806) DEBUG: inconsistent vblank ts/seq: last 443.868225/17467, current 443.978668/17477: elapsed=110439.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = flip
last_ts = 443.879272
last_received_ts = 443.878967
last_seq = 17468
current_ts = 443.989716
current_received_ts = 443.989380
current_seq = 17478
count = 49
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 443.879272/17468, current 443.989716/17478: elapsed=110447.0us expected=109863.1us +- 549.3us, error 0.5%
(kms_flip:2806) DEBUG: inconsistent flip ts/seq: last 443.879272/17468, current 443.989716/17478: elapsed=110447.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = vblank
last_ts = 443.978668
last_received_ts = 443.978912
last_seq = 17477
current_ts = 444.089142
current_received_ts = 444.089355
current_seq = 17487
count = 49
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 443.978668/17477, current 444.089142/17487: elapsed=110468.0us expected=109863.1us +- 549.3us, error 0.6%
(kms_flip:2806) DEBUG: inconsistent vblank ts/seq: last 443.978668/17477, current 444.089142/17487: elapsed=110468.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = flip
last_ts = 443.989716
last_received_ts = 443.989380
last_seq = 17478
current_ts = 444.100189
current_received_ts = 444.100098
current_seq = 17488
count = 50
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 443.989716/17478, current 444.100189/17488: elapsed=110474.0us expected=109863.1us +- 549.3us, error 0.6%
(kms_flip:2806) DEBUG: inconsistent flip ts/seq: last 443.989716/17478, current 444.100189/17488: elapsed=110474.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = vblank
last_ts = 444.089142
last_received_ts = 444.089355
last_seq = 17487
current_ts = 444.199677
current_received_ts = 444.199768
current_seq = 17497
count = 50
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 444.089142/17487, current 444.199677/17497: elapsed=110520.0us expected=109863.1us +- 549.3us, error 0.6%
(kms_flip:2806) DEBUG: inconsistent vblank ts/seq: last 444.089142/17487, current 444.199677/17497: elapsed=110520.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = flip
last_ts = 444.100189
last_received_ts = 444.100098
last_seq = 17488
current_ts = 444.210724
current_received_ts = 444.210541
current_seq = 17498
count = 51
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 444.100189/17488, current 444.210724/17498: elapsed=110519.0us expected=109863.1us +- 549.3us, error 0.6%
(kms_flip:2806) DEBUG: inconsistent flip ts/seq: last 444.100189/17488, current 444.210724/17498: elapsed=110519.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = vblank
last_ts = 444.199677
last_received_ts = 444.199768
last_seq = 17497
current_ts = 444.310181
current_received_ts = 444.310425
current_seq = 17507
count = 51
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 444.199677/17497, current 444.310181/17507: elapsed=110519.0us expected=109863.1us +- 549.3us, error 0.6%
(kms_flip:2806) DEBUG: inconsistent vblank ts/seq: last 444.199677/17497, current 444.310181/17507: elapsed=110519.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = flip
last_ts = 444.210724
last_received_ts = 444.210541
last_seq = 17498
current_ts = 444.321228
current_received_ts = 444.320984
current_seq = 17508
count = 52
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 444.210724/17498, current 444.321228/17508: elapsed=110522.0us expected=109863.1us +- 549.3us, error 0.6%
(kms_flip:2806) DEBUG: inconsistent flip ts/seq: last 444.210724/17498, current 444.321228/17508: elapsed=110522.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = vblank
last_ts = 444.310181
last_received_ts = 444.310425
last_seq = 17507
current_ts = 444.420715
current_received_ts = 444.420929
current_seq = 17517
count = 52
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 444.310181/17507, current 444.420715/17517: elapsed=110523.0us expected=109863.1us +- 549.3us, error 0.6%
(kms_flip:2806) DEBUG: inconsistent vblank ts/seq: last 444.310181/17507, current 444.420715/17517: elapsed=110523.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = flip
last_ts = 444.321228
last_received_ts = 444.320984
last_seq = 17508
current_ts = 444.431763
current_received_ts = 444.431610
current_seq = 17518
count = 53
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 444.321228/17508, current 444.431763/17518: elapsed=110520.0us expected=109863.1us +- 549.3us, error 0.6%
(kms_flip:2806) DEBUG: inconsistent flip ts/seq: last 444.321228/17508, current 444.431763/17518: elapsed=110520.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = vblank
last_ts = 444.420715
last_received_ts = 444.420929
last_seq = 17517
current_ts = 444.531219
current_received_ts = 444.531464
current_seq = 17527
count = 53
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 444.420715/17517, current 444.531219/17527: elapsed=110522.0us expected=109863.1us +- 549.3us, error 0.6%
(kms_flip:2806) DEBUG: inconsistent vblank ts/seq: last 444.420715/17517, current 444.531219/17527: elapsed=110522.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = flip
last_ts = 444.431763
last_received_ts = 444.431610
last_seq = 17518
current_ts = 444.542267
current_received_ts = 444.542114
current_seq = 17528
count = 54
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 444.431763/17518, current 444.542267/17528: elapsed=110518.0us expected=109863.1us +- 549.3us, error 0.6%
(kms_flip:2806) DEBUG: inconsistent flip ts/seq: last 444.431763/17518, current 444.542267/17528: elapsed=110518.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = vblank
last_ts = 444.531219
last_received_ts = 444.531464
last_seq = 17527
current_ts = 444.641754
current_received_ts = 444.641968
current_seq = 17537
count = 54
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 444.531219/17527, current 444.641754/17537: elapsed=110518.0us expected=109863.1us +- 549.3us, error 0.6%
(kms_flip:2806) DEBUG: inconsistent vblank ts/seq: last 444.531219/17527, current 444.641754/17537: elapsed=110518.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = flip
last_ts = 444.542267
last_received_ts = 444.542114
last_seq = 17528
current_ts = 444.652802
current_received_ts = 444.652557
current_seq = 17538
count = 55
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 444.542267/17528, current 444.652802/17538: elapsed=110522.0us expected=109863.1us +- 549.3us, error 0.6%
(kms_flip:2806) DEBUG: inconsistent flip ts/seq: last 444.542267/17528, current 444.652802/17538: elapsed=110522.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = vblank
last_ts = 444.641754
last_received_ts = 444.641968
last_seq = 17537
current_ts = 444.752258
current_received_ts = 444.752350
current_seq = 17547
count = 55
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 444.641754/17537, current 444.752258/17547: elapsed=110518.0us expected=109863.1us +- 549.3us, error 0.6%
(kms_flip:2806) DEBUG: inconsistent vblank ts/seq: last 444.641754/17537, current 444.752258/17547: elapsed=110518.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = flip
last_ts = 444.652802
last_received_ts = 444.652557
last_seq = 17538
current_ts = 444.763306
current_received_ts = 444.763123
current_seq = 17548
count = 56
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 444.652802/17538, current 444.763306/17548: elapsed=110521.0us expected=109863.1us +- 549.3us, error 0.6%
(kms_flip:2806) DEBUG: inconsistent flip ts/seq: last 444.652802/17538, current 444.763306/17548: elapsed=110521.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = vblank
last_ts = 444.752258
last_received_ts = 444.752350
last_seq = 17547
current_ts = 444.862793
current_received_ts = 444.862885
current_seq = 17557
count = 56
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 444.752258/17547, current 444.862793/17557: elapsed=110524.0us expected=109863.1us +- 549.3us, error 0.6%
(kms_flip:2806) DEBUG: inconsistent vblank ts/seq: last 444.752258/17547, current 444.862793/17557: elapsed=110524.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = flip
last_ts = 444.763306
last_received_ts = 444.763123
last_seq = 17548
current_ts = 444.873840
current_received_ts = 444.873566
current_seq = 17558
count = 57
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 444.763306/17548, current 444.873840/17558: elapsed=110518.0us expected=109863.1us +- 549.3us, error 0.6%
(kms_flip:2806) DEBUG: inconsistent flip ts/seq: last 444.763306/17548, current 444.873840/17558: elapsed=110518.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = vblank
last_ts = 444.862793
last_received_ts = 444.862885
last_seq = 17557
current_ts = 444.973297
current_received_ts = 444.973541
current_seq = 17567
count = 57
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 444.862793/17557, current 444.973297/17567: elapsed=110522.0us expected=109863.1us +- 549.3us, error 0.6%
(kms_flip:2806) DEBUG: inconsistent vblank ts/seq: last 444.862793/17557, current 444.973297/17567: elapsed=110522.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = flip
last_ts = 444.873840
last_received_ts = 444.873566
last_seq = 17558
current_ts = 444.984344
current_received_ts = 444.984161
current_seq = 17568
count = 58
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 444.873840/17558, current 444.984344/17568: elapsed=110518.0us expected=109863.1us +- 549.3us, error 0.6%
(kms_flip:2806) DEBUG: inconsistent flip ts/seq: last 444.873840/17558, current 444.984344/17568: elapsed=110518.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = vblank
last_ts = 444.973297
last_received_ts = 444.973541
last_seq = 17567
current_ts = 445.083832
current_received_ts = 445.084137
current_seq = 17577
count = 58
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 444.973297/17567, current 445.083832/17577: elapsed=110530.0us expected=109863.1us +- 549.3us, error 0.6%
(kms_flip:2806) DEBUG: inconsistent vblank ts/seq: last 444.973297/17567, current 445.083832/17577: elapsed=110530.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = flip
last_ts = 444.984344
last_received_ts = 444.984161
last_seq = 17568
current_ts = 445.094910
current_received_ts = 445.094757
current_seq = 17578
count = 59
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 444.984344/17568, current 445.094910/17578: elapsed=110547.0us expected=109863.1us +- 549.3us, error 0.6%
(kms_flip:2806) DEBUG: inconsistent flip ts/seq: last 444.984344/17568, current 445.094910/17578: elapsed=110547.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = vblank
last_ts = 445.083832
last_received_ts = 445.084137
last_seq = 17577
current_ts = 445.194427
current_received_ts = 445.194550
current_seq = 17587
count = 59
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 445.083832/17577, current 445.194427/17587: elapsed=110592.0us expected=109863.1us +- 549.3us, error 0.7%
(kms_flip:2806) DEBUG: inconsistent vblank ts/seq: last 445.083832/17577, current 445.194427/17587: elapsed=110592.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = flip
last_ts = 445.094910
last_received_ts = 445.094757
last_seq = 17578
current_ts = 445.205475
current_received_ts = 445.205200
current_seq = 17588
count = 60
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 445.094910/17578, current 445.205475/17588: elapsed=110585.0us expected=109863.1us +- 549.3us, error 0.7%
(kms_flip:2806) DEBUG: inconsistent flip ts/seq: last 445.094910/17578, current 445.205475/17588: elapsed=110585.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = vblank
last_ts = 445.194427
last_received_ts = 445.194550
last_seq = 17587
current_ts = 445.305023
current_received_ts = 445.305237
current_seq = 17597
count = 60
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 445.194427/17587, current 445.305023/17597: elapsed=110584.0us expected=109863.1us +- 549.3us, error 0.7%
(kms_flip:2806) DEBUG: inconsistent vblank ts/seq: last 445.194427/17587, current 445.305023/17597: elapsed=110584.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = flip
last_ts = 445.205475
last_received_ts = 445.205200
last_seq = 17588
current_ts = 445.316071
current_received_ts = 445.315887
current_seq = 17598
count = 61
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 445.205475/17588, current 445.316071/17598: elapsed=110590.0us expected=109863.1us +- 549.3us, error 0.7%
(kms_flip:2806) DEBUG: inconsistent flip ts/seq: last 445.205475/17588, current 445.316071/17598: elapsed=110590.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = vblank
last_ts = 445.305023
last_received_ts = 445.305237
last_seq = 17597
current_ts = 445.415588
current_received_ts = 445.415741
current_seq = 17607
count = 61
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 445.305023/17597, current 445.415588/17607: elapsed=110586.0us expected=109863.1us +- 549.3us, error 0.7%
(kms_flip:2806) DEBUG: inconsistent vblank ts/seq: last 445.305023/17597, current 445.415588/17607: elapsed=110586.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = flip
last_ts = 445.316071
last_received_ts = 445.315887
last_seq = 17598
current_ts = 445.426666
current_received_ts = 445.426544
current_seq = 17608
count = 62
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 445.316071/17598, current 445.426666/17608: elapsed=110586.0us expected=109863.1us +- 549.3us, error 0.7%
(kms_flip:2806) DEBUG: inconsistent flip ts/seq: last 445.316071/17598, current 445.426666/17608: elapsed=110586.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = vblank
last_ts = 445.415588
last_received_ts = 445.415741
last_seq = 17607
current_ts = 445.526184
current_received_ts = 445.526459
current_seq = 17617
count = 62
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 445.415588/17607, current 445.526184/17617: elapsed=110588.0us expected=109863.1us +- 549.3us, error 0.7%
(kms_flip:2806) DEBUG: inconsistent vblank ts/seq: last 445.415588/17607, current 445.526184/17617: elapsed=110588.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = flip
last_ts = 445.426666
last_received_ts = 445.426544
last_seq = 17608
current_ts = 445.537262
current_received_ts = 445.537109
current_seq = 17618
count = 63
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 445.426666/17608, current 445.537262/17618: elapsed=110587.0us expected=109863.1us +- 549.3us, error 0.7%
(kms_flip:2806) DEBUG: inconsistent flip ts/seq: last 445.426666/17608, current 445.537262/17618: elapsed=110587.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = vblank
last_ts = 445.526184
last_received_ts = 445.526459
last_seq = 17617
current_ts = 445.636780
current_received_ts = 445.636993
current_seq = 17627
count = 63
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 445.526184/17617, current 445.636780/17627: elapsed=110590.0us expected=109863.1us +- 549.3us, error 0.7%
(kms_flip:2806) DEBUG: inconsistent vblank ts/seq: last 445.526184/17617, current 445.636780/17627: elapsed=110590.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = flip
last_ts = 445.537262
last_received_ts = 445.537109
last_seq = 17618
current_ts = 445.647827
current_received_ts = 445.647675
current_seq = 17628
count = 64
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 445.537262/17618, current 445.647827/17628: elapsed=110586.0us expected=109863.1us +- 549.3us, error 0.7%
(kms_flip:2806) DEBUG: inconsistent flip ts/seq: last 445.537262/17618, current 445.647827/17628: elapsed=110586.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = vblank
last_ts = 445.636780
last_received_ts = 445.636993
last_seq = 17627
current_ts = 445.747375
current_received_ts = 445.747650
current_seq = 17637
count = 64
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 445.636780/17627, current 445.747375/17637: elapsed=110584.0us expected=109863.1us +- 549.3us, error 0.7%
(kms_flip:2806) DEBUG: inconsistent vblank ts/seq: last 445.636780/17627, current 445.747375/17637: elapsed=110584.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = flip
last_ts = 445.647827
last_received_ts = 445.647675
last_seq = 17628
current_ts = 445.758423
current_received_ts = 445.758240
current_seq = 17638
count = 65
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 445.647827/17628, current 445.758423/17638: elapsed=110588.0us expected=109863.1us +- 549.3us, error 0.7%
(kms_flip:2806) DEBUG: inconsistent flip ts/seq: last 445.647827/17628, current 445.758423/17638: elapsed=110588.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = vblank
last_ts = 445.747375
last_received_ts = 445.747650
last_seq = 17637
current_ts = 445.857941
current_received_ts = 445.858246
current_seq = 17647
count = 65
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 445.747375/17637, current 445.857941/17647: elapsed=110588.0us expected=109863.1us +- 549.3us, error 0.7%
(kms_flip:2806) DEBUG: inconsistent vblank ts/seq: last 445.747375/17637, current 445.857941/17647: elapsed=110588.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = flip
last_ts = 445.758423
last_received_ts = 445.758240
last_seq = 17638
current_ts = 445.869019
current_received_ts = 445.868774
current_seq = 17648
count = 66
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 445.758423/17638, current 445.869019/17648: elapsed=110586.0us expected=109863.1us +- 549.3us, error 0.7%
(kms_flip:2806) DEBUG: inconsistent flip ts/seq: last 445.758423/17638, current 445.869019/17648: elapsed=110586.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = vblank
last_ts = 445.857941
last_received_ts = 445.858246
last_seq = 17647
current_ts = 445.968536
current_received_ts = 445.968750
current_seq = 17657
count = 66
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 445.857941/17647, current 445.968536/17657: elapsed=110589.0us expected=109863.1us +- 549.3us, error 0.7%
(kms_flip:2806) DEBUG: inconsistent vblank ts/seq: last 445.857941/17647, current 445.968536/17657: elapsed=110589.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = flip
last_ts = 445.869019
last_received_ts = 445.868774
last_seq = 17648
current_ts = 445.979584
current_received_ts = 445.979340
current_seq = 17658
count = 67
seq_step = 1
(kms_flip:2806) DEBUG: flip ts/seq: last 445.869019/17648, current 445.979584/17658: elapsed=110586.0us expected=109863.1us +- 549.3us, error 0.7%
(kms_flip:2806) DEBUG: inconsistent flip ts/seq: last 445.869019/17648, current 445.979584/17658: elapsed=110586.0us expected=109863.1us
(kms_flip:2806) DEBUG: name = vblank
last_ts = 445.968536
last_received_ts = 445.968750
last_seq = 17657
current_ts = 446.079132
current_received_ts = 446.079376
current_seq = 17667
count = 67
seq_step = 10
(kms_flip:2806) DEBUG: vblank ts/seq: last 445.968536/17657, current 446.079132/17667: elapsed=110597.0us expected=109863.1us +- 549.3us, error 0.7%
(kms_flip:2806) DEBUG: inconsistent vblank ts/seq: last 445.968536/17657, current 446.079132/17667: elapsed=110597.0us expected=109863.1us
(kms_flip:2806) INFO: Event flip: expected 682, counted 680, passrate = 61.76%, encoder type 2
(kms_flip:2806) DEBUG: dropped frames, expected 682, counted 680, passrate = 61.76%, encoder type 2
(kms_flip:2806) INFO: Event vblank: expected 682, counted 680, passrate = 60.29%, encoder type 2
(kms_flip:2806) DEBUG: dropped frames, expected 682, counted 680, passrate = 60.29%, encoder type 2
(kms_flip:2806) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1709:
(kms_flip:2806) CRITICAL: Failed assertion: !retried
(kms_flip:2806) igt_core-INFO: Stack trace:
(kms_flip:2806) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2806) igt_core-INFO:   #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
(kms_flip:2806) igt_core-INFO:   #2 ../tests/kms_flip.c:1889 run_test()
(kms_flip:2806) igt_core-INFO:   #3 ../tests/kms_flip.c:2122 __igt_unique____real_main2045()
(kms_flip:2806) igt_core-INFO:   #4 ../tests/kms_flip.c:2045 main()
(kms_flip:2806) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2806) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2806) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-eDP1: FAIL (21.187s)
Dmesg
<6> [541.147582] Console: switching to colour dummy device 80x25
<6> [541.147890] [IGT] kms_flip: executing
<7> [541.151627] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [541.153169] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [541.169436] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [541.175264] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:515:DP-1]
<7> [541.175280] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:515:DP-1]
<7> [541.179491] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:515:DP-1] disconnected
<7> [541.179738] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:527:HDMI-A-1]
<7> [541.179742] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:527:HDMI-A-1]
<7> [541.183938] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:527:HDMI-A-1] disconnected
<7> [541.184182] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:533:DP-2]
<7> [541.184185] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:533:DP-2]
<7> [541.184449] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port D/TC#1: TC port mode reset (disconnected -> tbt-alt) pin assignment: - max lanes: 4
<7> [541.184640] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:533:DP-2] disconnected
<7> [541.184882] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:542:HDMI-A-2]
<7> [541.184885] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:542:HDMI-A-2]
<7> [541.185166] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port E/TC#2: TC port mode reset (disconnected -> legacy) pin assignment: - max lanes: 4
<7> [541.189425] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:542:HDMI-A-2] disconnected
<7> [541.189672] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:546:DP-3]
<7> [541.189675] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:546:DP-3]
<7> [541.189889] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port F/TC#3: TC port mode reset (disconnected -> tbt-alt) pin assignment: - max lanes: 4
<7> [541.190074] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:546:DP-3] disconnected
<7> [541.190325] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:555:DP-4]
<7> [541.190329] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:555:DP-4]
<7> [541.190537] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port G/TC#4: TC port mode reset (disconnected -> tbt-alt) pin assignment: - max lanes: 4
<7> [541.190723] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:555:DP-4] disconnected
<7> [541.191053] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:506:eDP-1]
<7> [541.191057] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:506:eDP-1]
<7> [541.191748] i915 0000:00:02.0: [drm:intel_dp_read_dsc_dpcd [i915]] DSC DPCD: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
<7> [541.192159] i915 0000:00:02.0: [drm:intel_dp_print_rates [i915]] source rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 675000, 810000
<7> [541.192289] i915 0000:00:02.0: [drm:intel_dp_print_rates [i915]] sink rates: 162000, 216000, 243000, 270000, 324000, 378000, 432000, 540000
<7> [541.192410] i915 0000:00:02.0: [drm:intel_dp_print_rates [i915]] common rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000
<7> [541.192555] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:506:eDP-1] Assigning EDID-1.4 digital sink color depth as 10 bpc.
<7> [541.192561] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:506:eDP-1] ELD monitor
<7> [541.192563] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:506:eDP-1] ELD size 20, SAD count 0
<7> [541.192580] i915 0000:00:02.0: [drm:intel_dp_set_edid [i915]] [CONNECTOR:506:eDP-1] VRR capable: no
<7> [541.192700] i915 0000:00:02.0: [drm:intel_dp_set_edid [i915]] [CONNECTOR:506:eDP-1] DFP max bpc 0, max dotclock 0, TMDS clock 0-0, PCON Max FRL BW 0Gbps
<7> [541.192815] i915 0000:00:02.0: [drm:intel_dp_set_edid [i915]] [CONNECTOR:506:eDP-1] RGB->YcbCr conversion? no, YCbCr 4:2:0 allowed? yes, YCbCr 4:4:4->4:2:0 conversion? no
<7> [541.193613] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:506:eDP-1] probed modes:
<7> [541.193617] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2880x1800": 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x48 0xa
<7> [541.193630] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:515:DP-1]
<7> [541.193633] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:515:DP-1]
<7> [541.197849] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:515:DP-1] disconnected
<7> [541.197860] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:527:HDMI-A-1]
<7> [541.197862] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:527:HDMI-A-1]
<7> [541.202055] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:527:HDMI-A-1] disconnected
<7> [541.202064] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:533:DP-2]
<7> [541.202067] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:533:DP-2]
<7> [541.202301] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:533:DP-2] disconnected
<7> [541.202310] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:542:HDMI-A-2]
<7> [541.202313] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:542:HDMI-A-2]
<7> [541.206590] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:542:HDMI-A-2] disconnected
<7> [541.206601] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:546:DP-3]
<7> [541.206604] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:546:DP-3]
<7> [541.206819] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:546:DP-3] disconnected
<7> [541.206828] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:555:DP-4]
<7> [541.206830] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:555:DP-4]
<7> [541.207040] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:555:DP-4] disconnected
<6> [541.207614] [IGT] kms_flip: starting subtest flip-vs-absolute-wf_vblank
<6> [541.210100] [IGT] kms_flip: starting dynamic subtest A-eDP1
<7> [541.210407] i915 0000:00:02.0: [drm:intel_dpt_create [i915]] Allocating dpt from smem
<7> [541.210572] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:563]
<7> [541.210637] i915 0000:00:02.0: [drm:intel_dpt_create [i915]] Allocating dpt from smem
<7> [541.210790] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:566]
<7> [541.213675] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [541.214000] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [541.241104] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [541.241397] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [541.249722] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [541.249997] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [541.267412] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [541.267661] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [541.293258] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:149:pipe A]
<7> [541.293380] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [541.293519] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [541.293646] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [541.293769] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [541.293892] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in dp_m_n (expected tu 64 data 7483046/8388608 link 623587/524288, found tu 0, data 0/0 link 0/0)
<7> [541.294016] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [541.294139] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [541.294263] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [541.294410] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [541.294553] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [541.294681] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [541.294817] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [541.294940] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [541.295059] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [541.295177] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1801, found 0)
<7> [541.295310] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [541.295439] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [541.295560] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1878, found 0)
<7> [541.295677] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1878, found 0)
<7> [541.295794] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [541.295912] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [541.296031] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [541.296149] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [541.296274] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [541.296409] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [541.296533] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [541.296650] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1801, found 0)
<7> [541.296765] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [541.296879] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [541.296994] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1878, found 0)
<7> [541.297108] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1878, found 0)
<7> [541.297223] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [541.297351] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [541.297473] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [541.297599] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in enhanced_framing (expected yes, found no)
<7> [541.297724] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [541.297845] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 513820, found 0)
<7> [541.297966] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 513820, found 0)
<7> [541.298083] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in port_clock (expected 432000, found 0)
<7> [541.298202] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in set_context_latency (expected 1, found 0)
<7> [541.298328] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:149:pipe A] fastset requirement not met, forcing full modeset
<7> [541.298454] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:149:pipe A] releasing DPLL 0
<7> [541.298617] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [541.298735] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:149:pipe A] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [541.298869] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:33:plane 1A] ddb ( 0 - 4001) -> ( 0 - 0), size 4001 -> 0
<7> [541.298989] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:143:cursor A] ddb (4001 - 4096) -> ( 0 - 0), size 95 -> 0
<7> [541.299105] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33: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> [541.299219] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] lines 2, 16, 16, 19, 19, 28, 0, 0, 0, 3, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [541.299345] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] blocks 49, 385, 385, 457, 457, 673, 0, 0, 63, 73, 87 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [541.299479] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] min_ddb 55, 425, 425, 504, 504, 742, 0, 0, 64, 82, 88 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [541.299616] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:149:pipe A] data rate 0 num active planes 0
<7> [541.299743] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 28913 required 0 qgv_peak_bw: 51200
<7> [541.299867] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 1: max bw 38000 required 0 qgv_peak_bw: 51200
<7> [541.299988] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 51200
<7> [541.300109] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 51200
<7> [541.300228] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] Matching peaks QGV bw: 51200 for required data rate: 0
<7> [541.300377] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:149:pipe A] min cdclk: 256910 kHz -> 0 kHz
<7> [541.300520] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 32114 kHz -> 0 kHz
<7> [541.300645] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Can change cdclk via squashing
<7> [541.300766] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz
<7> [541.300884] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [541.301025] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:149:pipe A] enable: no [modeset]
<7> [541.301148] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:33:plane 1A] fb: [NOFB], visible: no
<7> [541.301274] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:63:plane 2A] fb: [NOFB], visible: no
<7> [541.301399] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:93:plane 3A] fb: [NOFB], visible: no
<7> [541.301522] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:123:plane 4A] fb: [NOFB], visible: no
<7> [541.301645] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:133:plane 5A] fb: [NOFB], visible: no
<7> [541.301763] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:143:cursor A] fb: [NOFB], visible: no
<7> [541.301935] i915 0000:00:02.0: [drm:intel_psr_disable_locked [i915]] Disabling PSR2
<7> [541.302968] i915 0000:00:02.0: [drm:intel_edp_backlight_off [i915]]
<7> [541.508564] i915 0000:00:02.0: [drm:intel_backlight_set_pwm_level [i915]] [CONNECTOR:506:eDP-1] set backlight PWM = 0
<7> [541.509352] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [541.514983] i915 0000:00:02.0: [drm:intel_pps_off_unlocked [i915]] [ENCODER:505:DDI A/PHY A] PPS 0 turn panel power off
<7> [541.515310] i915 0000:00:02.0: [drm:icp_irq_handler [i915]] hotplug event received, stat 0x00010000, dig 0x00000089, pins 0x00000010, long 0x00000000
<7> [541.515506] i915 0000:00:02.0: [drm:intel_pps_off_unlocked [i915]] [ENCODER:505:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [541.515997] i915 0000:00:02.0: [drm:wait_panel_status [i915]] [ENCODER:505:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [541.516020] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] digital hpd on [ENCODER:505:DDI A/PHY A] - short
<7> [541.566811] i915 0000:00:02.0: [drm:wait_panel_status [i915]] Wait complete
<7> [541.567444] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:149:pipe A]
<7> [541.567670] i915 0000:00:02.0: [drm:intel_dp_hpd_pulse [i915]] ignoring short hpd on eDP [ENCODER:505:DDI A/PHY A]
<7> [541.568451] i915 0000:00:02.0: [drm:icp_irq_handler [i915]] hotplug event received, stat 0x00010000, dig 0x0000008a, pins 0x00000010, long 0x00000010
<7> [541.568835] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [541.569031] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] digital hpd on [ENCODER:505:DDI A/PHY A] - long
<7> [541.569405] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling AUX_A
<7> [541.569586] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [541.569871] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:505:DDI A/PHY A]
<7> [541.570078] i915 0000:00:02.0: [drm:intel_dp_hpd_pulse [i915]] ignoring long hpd on eDP [ENCODER:505:DDI A/PHY A]
<7> [541.570239] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:514:DDI B/PHY B]
<7> [541.570619] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:516:DP-MST A]
<7> [541.570949] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:517:DP-MST B]
<7> [541.571163] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:518:DP-MST C]
<7> [541.571384] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:519:DP-MST D]
<7> [541.571581] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:532:DDI TC1/PHY TC1]
<7> [541.571770] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:534:DP-MST A]
<7> [541.571958] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:535:DP-MST B]
<7> [541.572142] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:536:DP-MST C]
<7> [541.572348] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:537:DP-MST D]
<7> [541.572557] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:541:DDI TC2/PHY TC2]
<7> [541.572798] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:545:DDI TC3/PHY TC3]
<7> [541.573026] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:547:DP-MST A]
<7> [541.573185] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:548:DP-MST B]
<7> [541.573359] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:549:DP-MST C]
<7> [541.573519] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:550:DP-MST D]
<7> [541.573689] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:554:DDI TC4/PHY TC4]
<7> [541.573835] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:556:DP-MST A]
<7> [541.573981] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:557:DP-MST B]
<7> [541.574096] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:558:DP-MST C]
<7> [541.574208] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:559:DP-MST D]
<7> [541.574349] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:506:eDP-1]
<7> [541.574556] i915 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [i915]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [541.574722] i915 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [i915]] Changing mbus joined: yes -> no (pipe: *)
<7> [541.574861] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [541.575088] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_A
<7> [541.575294] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:149:pipe A]
<7> [541.575479] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Post changing CDCLK to 172800 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [541.575641] i915 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [i915]] aud_ts_cdclk set to M=60, N=432
<7> [541.575937] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:267:pipe B]
<7> [541.576070] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:385:pipe C]
<7> [541.576168] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:503:pipe D]
<7> [541.576282] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:149:pipe A]
<7> [541.576306] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:506:eDP-1]
<7> [541.576361] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:506:eDP-1] Limiting display bpp to 24 (EDID bpp 30, max requested bpp 24, max platform bpp 36)
<7> [541.576499] i915 0000:00:02.0: [drm:intel_dp_compute_config_limits [i915]] [ENCODER:505:DDI A/PHY A][CRTC:149:pipe A] DP link limits: pixel clock 513820 kHz DSC off max lanes 4 max rate 540000 max pipe_bpp 24 min link_bpp 18.0000 max link_bpp 24.0000
<7> [541.576614] i915 0000:00:02.0: [drm:intel_dp_compute_link_config [i915]] DP lane count 4 clock 432000 bpp input 24 compressed 0.0000 link rate required 1541460 available 1728000
<7> [541.576731] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:149:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [541.576853] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:505:DDI A/PHY A] [CRTC:149:pipe A]
<7> [541.576972] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [541.577098] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [541.577221] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [541.577360] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [541.577484] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 7483046/8388608 link 623587/524288)
<7> [541.577607] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [541.577728] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [541.577850] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [541.577970] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [541.578089] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)
<7> [541.578211] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)
<7> [541.578348] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)
<7> [541.578473] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)
<7> [541.578594] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [541.578710] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1801)
<7> [541.578826] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)
<7> [541.578945] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)
<7> [541.579064] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1878)
<7> [541.579185] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1878)
<7> [541.579312] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [541.579437] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [541.579558] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)
<7> [541.579677] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)
<7> [541.579796] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)
<7> [541.579914] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)
<7> [541.580032] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [541.580150] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1801)
<7> [541.580274] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)
<7> [541.580398] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)
<7> [541.580519] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1878)
<7> [541.580637] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1878)
<7> [541.580754] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [541.580871] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [541.580988] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [541.581105] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in enhanced_framing (expected no, found yes)
<7> [541.581223] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [541.581349] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 513820)
<7> [541.581470] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 513820)
<7> [541.581587] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in port_clock (expected 0, found 432000)
<7> [541.581706] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in set_context_latency (expected 0, found 1)
<7> [541.581822] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:149:pipe A] fastset requirement not met, forcing full modeset
<7> [541.581966] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [541.582084] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:149:pipe A] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x0 -> 0x1
<7> [541.582220] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:33:plane 1A] ddb ( 0 - 0) -> ( 0 - 4001), size 0 -> 4001
<7> [541.582347] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:143:cursor A] ddb ( 0 - 0) -> (4001 - 4096), size 0 -> 95
<7> [541.582463] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33: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> [541.582577] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 16, 16, 19, 19, 28, 0, 0, 0, 4, 0
<7> [541.582690] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 92, 365, 365, 434, 434, 638, 0, 0, 197, 92, 197
<7> [541.582804] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 183, 456, 456, 547, 547, 729, 0, 0, 198, 183, 198
<7> [541.582930] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:149:pipe A] data rate 2055280 num active planes 1
<7> [541.583060] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 28913 required 2159 qgv_peak_bw: 51200
<7> [541.583194] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 1: max bw 38000 required 2159 qgv_peak_bw: 51200
<7> [541.583329] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 2: max bw 38000 required 2159 qgv_peak_bw: 51200
<7> [541.583448] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 3: max bw 38000 required 2159 qgv_peak_bw: 51200
<7> [541.583569] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] Matching peaks QGV bw: 51200 for required data rate: 2159
<7> [541.583691] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:149:pipe A] min cdclk: 0 kHz -> 256910 kHz
<7> [541.583828] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 32114 kHz
<7> [541.583949] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Can change cdclk via squashing
<7> [541.584068] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 307200 kHz, actual 307200 kHz
<7> [541.584185] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [541.584329] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:149:pipe A] allocated DPLL 0
<7> [541.584460] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:149:pipe A] reserving DPLL 0
<7> [541.584591] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:149:pipe A] enable: yes [modeset]
<7> [541.584720] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [541.584843] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [541.584964] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [541.585084] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [541.585204] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [541.585333] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [541.585458] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dp m_n: lanes: 4; data_m: 7483046, data_n: 8388608, link_m: 623587, link_n: 524288, tu: 64
<7> [541.585579] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dp m2_n2: lanes: 4; data_m: 0, data_n: 0, link_m: 0, link_n: 0, tu: 0
<7> [541.585698] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] fec: disabled, enhanced framing: enabled
<7> [541.585817] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sdp split: disabled
<7> [541.585934] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled
<7> [541.586051] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] minimum HBlank: 0
<7> [541.586168] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [541.586289] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] DP SDP: VSC, revision 4, length 14
<7> [541.586412] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pixelformat: RGB
<7> [541.586536] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] colorimetry: sRGB
<7> [541.586653] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] bpc: 0
<7> [541.586770] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dynamic range: VESA range
<7> [541.586887] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] content type: Not defined
<7> [541.587003] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1
<7> [541.587123] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 1
<7> [541.587244] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1878, vmax: 1878, flipline: 1878, pipeline full: 0, guardband: 76 vsync start: 75, vsync end: 69
<7> [541.587380] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1802, vmax vblank: 1802, vmin vtotal: 1878, vmax vtotal: 1878
<7> [541.587503] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [541.587620] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "2880x1800": 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x48 0xa
<7> [541.587737] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "2880x1800": 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x48 0xa
<7> [541.587854] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=513820, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1801-1878 vs=1803-1809 vt=1878, flags=0xa
<7> [541.587971] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "2880x1800": 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x40 0xa
<7> [541.588088] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=513820, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1801-1878 vs=1803-1809 vt=1878, flags=0xa
<7> [541.588204] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 432000, pipe src: 2880x1800+0+0, pixel rate 513820, min cdclk 256910
<7> [541.588336] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 48, ips linetime: 0
<7> [541.588455] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [541.588572] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [541.588688] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [541.588803] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: yes, tbt_mode: no
<7> [541.588920] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] c10pll_hw_state: clock: 432000, fracen: no,
<7> [541.589036] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] multiplier: 225, tx_clk_div: 1.
<7> [541.589151] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] c10pll_rawhw_state:
<7> [541.589270] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] tx: 0x10, cmn: 0x21
<7> [541.589386] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1
<7> [541.589503] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [541.589619] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [541.589735] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [541.589851] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23
<7> [541.589967] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [541.590081] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [541.590197] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [541.590367] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [541.590486] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [541.590604] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [541.590720] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [541.590837] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [541.590954] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [541.591069] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [541.591183] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [541.591310] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [541.591431] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [541.591548] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:33:plane 1A] fb: [FB:563] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [541.591668] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [541.591784] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [541.591899] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:63:plane 2A] fb: [NOFB], visible: no
<7> [541.592015] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:93:plane 3A] fb: [NOFB], visible: no
<7> [541.592131] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:123:plane 4A] fb: [NOFB], visible: no
<7> [541.592247] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:133:plane 5A] fb: [NOFB], visible: no
<7> [541.592378] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:143:cursor A] fb: [NOFB], visible: no
<7> [541.593292] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [541.593461] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [541.593600] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [541.596095] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [541.596732] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [541.597464] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_A
<7> [541.597849] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 307200 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [541.598160] i915 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [i915]] aud_ts_cdclk set to M=60, N=768
<7> [541.598469] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:505:DDI A/PHY A]
<7> [541.598733] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:514:DDI B/PHY B]
<7> [541.598985] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:516:DP-MST A]
<7> [541.599157] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:517:DP-MST B]
<7> [541.599306] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:518:DP-MST C]
<7> [541.599442] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:519:DP-MST D]
<7> [541.599573] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:532:DDI TC1/PHY TC1]
<7> [541.599706] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:534:DP-MST A]
<7> [541.599837] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:535:DP-MST B]
<7> [541.599966] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:536:DP-MST C]
<7> [541.600097] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:537:DP-MST D]
<7> [541.600225] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:541:DDI TC2/PHY TC2]
<7> [541.600378] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:545:DDI TC3/PHY TC3]
<7> [541.600517] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:547:DP-MST A]
<7> [541.600653] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:548:DP-MST B]
<7> [541.600773] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:549:DP-MST C]
<7> [541.600890] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:550:DP-MST D]
<7> [541.601007] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:554:DDI TC4/PHY TC4]
<7> [541.601124] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:556:DP-MST A]
<7> [541.601242] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:557:DP-MST B]
<7> [541.601376] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:558:DP-MST C]
<7> [541.601494] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:559:DP-MST D]
<7> [541.601644] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0xf
<7> [541.601870] i915 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [i915]] Changing mbus joined: no -> yes (pipe: *)
<7> [541.602004] i915 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [i915]] Updating dbuf ratio to 4 (mbus joined: yes)
<7> [541.602210] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling AUX_A
<7> [541.603291] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:149:pipe A]
<7> [541.603456] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [541.604921] i915 0000:00:02.0: [drm:intel_pps_on_unlocked [i915]] [ENCODER:505:DDI A/PHY A] PPS 0 turn panel power on
<7> [541.605062] i915 0000:00:02.0: [drm:wait_panel_power_cycle [i915]] [ENCODER:505:DDI A/PHY A] PPS 0 wait for panel power cycle (463 ms remaining)
<7> [542.076869] i915 0000:00:02.0: [drm:wait_panel_status [i915]] [ENCODER:505:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [542.148608] i915 0000:00:02.0: [drm:wait_panel_status [i915]] Wait complete
<7> [542.149100] i915 0000:00:02.0: [drm:intel_pps_on_unlocked [i915]] [ENCODER:505:DDI A/PHY A] PPS 0 wait for panel power on
<7> [542.149768] i915 0000:00:02.0: [drm:wait_panel_status [i915]] [ENCODER:505:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [542.181314] i915 0000:00:02.0: [drm:icp_irq_handler [i915]] hotplug event received, stat 0x00010000, dig 0x0000008a, pins 0x00000010, long 0x00000010
<7> [542.181959] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] digital hpd on [ENCODER:505:DDI A/PHY A] - long
<7> [542.182528] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [542.183170] i915 0000:00:02.0: [drm:intel_dp_hpd_pulse [i915]] ignoring long hpd on eDP [ENCODER:505:DDI A/PHY A]
<7> [542.204370] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port D/TC#1: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [542.268774] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port G/TC#4: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [542.268867] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port F/TC#3: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [542.269002] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port E/TC#2: TC port mode reset (legacy -> disconnected) pin assignment: - max lanes: 4
<7> [542.353093] i915 0000:00:02.0: [drm:wait_panel_status [i915]] Wait complete
<7> [542.353815] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:505:DDI A/PHY A] PPS 0 turning VDD on
<7> [542.354404] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:505:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [542.357785] i915 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX A/DDI A/PHY A: DPCD: 14 14 c4 41 00 00 01 c0 02 00 00 00 00 0b 80
<7> [542.358760] i915 0000:00:02.0: [drm:intel_dp_start_link_train [i915]] [CONNECTOR:506:eDP-1][ENCODER:505:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [542.359467] i915 0000:00:02.0: [drm:intel_dp_start_link_train [i915]] [CONNECTOR:506:eDP-1][ENCODER:505:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 06
<7> [542.360838] i915 0000:00:02.0: [drm:intel_dp_set_signal_levels [i915]] [CONNECTOR:506:eDP-1][ENCODER:505:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [542.361722] i915 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [i915]] [CONNECTOR:506:eDP-1][ENCODER:505:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [542.362774] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:506:eDP-1][ENCODER:505:DDI A/PHY A][DPRX] Clock recovery OK
<7> [542.362891] i915 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [i915]] [CONNECTOR:506:eDP-1][ENCODER:505:DDI A/PHY A][DPRX] Using DP training pattern TPS3
<7> [542.364228] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:506:eDP-1][ENCODER:505:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [542.364356] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:506:eDP-1][ENCODER:505:DDI A/PHY A][DPRX] Link Training passed at link rate = 432000, lane count = 4
<7> [542.365039] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [542.365288] i915 0000:00:02.0: [drm:intel_edp_backlight_on [i915]]
<7> [542.365402] i915 0000:00:02.0: [drm:intel_backlight_enable [i915]] pipe A
<7> [542.365580] i915 0000:00:02.0: [drm:intel_backlight_set_pwm_level [i915]] [CONNECTOR:506:eDP-1] set backlight PWM = 13892
<7> [542.376475] i915 0000:00:02.0: [drm:intel_psr_post_plane_update [i915]] Enabling PSR2
<7> [542.378092] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:506:eDP-1]
<7> [542.378365] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:149:pipe A]
<7> [544.934806] i915 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [i915]] [ENCODER:505:DDI A/PHY A] PPS 0 turning VDD off
<7> [544.935311] i915 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [i915]] [ENCODER:505:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x00000067
<7> [544.939961] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [544.940439] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [544.940875] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [545.021999] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [545.022465] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [545.049992] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [545.050473] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [545.050894] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [545.133220] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [545.133692] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [545.162060] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [545.162680] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [545.163172] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [545.244508] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [545.245106] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [545.273102] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [545.273639] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [545.274126] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [545.355603] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [545.356156] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [545.384092] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [545.384593] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [545.385042] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [545.466375] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [545.466917] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [545.495226] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [545.496160] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [545.496730] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [545.577756] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [545.578396] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [545.606128] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [545.606672] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [545.607132] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [545.688695] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [545.689330] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [545.718183] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [545.718663] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [545.719081] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [545.799758] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [545.800363] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [545.828171] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [545.828654] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [545.829103] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [545.911051] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [545.911616] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [545.939196] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [545.939717] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [545.940188] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [546.022279] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [546.022786] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [546.049952] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [546.050429] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [546.050853] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [546.132940] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [546.133473] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [546.161477] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [546.161971] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [546.162420] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [546.244532] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [546.245031] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [546.273024] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [546.273508] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [546.273960] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [546.355556] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [546.356175] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [546.383547] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [546.384032] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [546.384483] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [546.466008] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [546.466544] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [546.496106] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [546.496628] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [546.497055] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [546.577723] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [546.578201] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [546.605578] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [546.606052] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [546.606463] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [546.688410] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [546.688874] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [546.717231] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [546.717753] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [546.718267] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [546.800039] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [546.800555] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [546.827654] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [546.828177] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [546.828683] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [546.910855] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [546.911453] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [546.939174] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [546.939651] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [546.940103] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [547.022299] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [547.023043] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [547.050688] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [547.051177] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [547.051678] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [547.132925] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [547.133624] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [547.161700] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [547.162178] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [547.162615] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [547.244517] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [547.245073] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [547.272810] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [547.273291] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [547.273724] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [547.354736] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [547.355262] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [547.384899] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [547.385457] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [547.385987] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [547.466719] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [547.467276] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [547.494991] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [547.495502] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [547.495974] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [547.577843] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [547.578421] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [547.606066] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [547.606542] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [547.607013] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [547.688980] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [547.689565] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [547.717243] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [547.717757] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [547.718283] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [547.800026] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [547.800548] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [547.828350] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [547.828862] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [547.829336] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [547.910870] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [547.911362] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [547.939360] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [547.939858] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [547.940276] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [548.021747] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [548.022200] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [548.050449] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [548.050929] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [548.051352] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [548.133547] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [548.134104] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [548.161160] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [548.161648] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [548.162066] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [548.244530] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [548.245024] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [548.272878] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [548.273380] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [548.273797] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [548.355614] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [548.356170] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [548.383599] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [548.384125] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [548.384599] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [548.466677] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [548.467178] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [548.494283] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [548.494784] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [548.495296] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [548.577658] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [548.578199] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [548.606100] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [548.606613] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [548.607102] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [548.688611] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [548.689124] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [548.716847] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [548.717336] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [548.717811] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [548.799806] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [548.800358] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [548.828450] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [548.828950] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [548.829442] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [548.911390] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [548.911990] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [548.939221] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [548.939751] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [548.940247] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [549.022237] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [549.022914] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [549.049935] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [549.050424] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [549.050882] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [549.133338] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [549.133924] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [549.161397] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [549.161891] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [549.162364] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [549.244183] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [549.244713] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [549.272804] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [549.273332] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [549.273892] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [549.355622] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [549.356161] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [549.383180] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [549.383677] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [549.384150] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [549.466678] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [549.467237] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [549.494542] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [549.495042] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [549.495540] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [549.577803] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [549.578400] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [549.605932] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [549.606411] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [549.606869] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [549.688962] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [549.689481] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [549.717299] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [549.717808] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [549.718283] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [549.800039] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [549.800647] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [549.827694] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [549.828227] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [549.828750] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [549.910884] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [549.911461] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [549.938990] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [549.939497] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [549.939983] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [550.022090] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [550.022661] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [550.050372] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [550.050872] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [550.051294] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [550.133447] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [550.134045] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [550.161597] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [550.162126] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [550.162595] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [550.244587] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [550.245172] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [550.272682] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [550.273162] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [550.273584] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [553.426452] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:149:pipe A]
<7> [553.426825] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [553.427392] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [553.427923] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [553.428417] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [553.428927] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in dp_m_n (expected tu 64 data 7483046/8388608 link 623587/524288, found tu 0, data 0/0 link 0/0)
<7> [553.429394] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [553.429724] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [553.430084] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [553.430417] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [553.430738] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [553.431060] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [553.431242] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [553.431416] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [553.431655] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [553.431953] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1801, found 0)
<7> [553.432190] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [553.432438] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [553.432619] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1878, found 0)
<7> [553.432802] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1878, found 0)
<7> [553.432977] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [553.433152] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [553.433274] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [553.433413] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [553.433540] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [553.433661] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [553.433789] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [553.433912] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1801, found 0)
<7> [553.434029] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [553.434146] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [553.434266] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1878, found 0)
<7> [553.434383] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1878, found 0)
<7> [553.434500] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [553.434618] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [553.434752] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [553.434912] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in enhanced_framing (expected yes, found no)
<7> [553.435048] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [553.435165] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 513820, found 0)
<7> [553.435289] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 513820, found 0)
<7> [553.435405] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in port_clock (expected 432000, found 0)
<7> [553.435522] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in set_context_latency (expected 1, found 0)
<7> [553.435638] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:149:pipe A] fastset requirement not met, forcing full modeset
<7> [553.435756] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:149:pipe A] releasing DPLL 0
<7> [553.435945] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [553.436068] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:149:pipe A] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [553.436215] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:33:plane 1A] ddb ( 0 - 4001) -> ( 0 - 0), size 4001 -> 0
<7> [553.436347] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:143:cursor A] ddb (4001 - 4096) -> ( 0 - 0), size 95 -> 0
<7> [553.436470] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33: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> [553.436584] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] lines 4, 16, 16, 19, 19, 28, 0, 0, 0, 4, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [553.436712] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] blocks 92, 365, 365, 434, 434, 638, 0, 0, 197, 92, 197 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [553.436838] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] min_ddb 183, 456, 456, 547, 547, 729, 0, 0, 198, 183, 198 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [553.436969] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:149:pipe A] data rate 0 num active planes 0
<7> [553.437098] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 28913 required 0 qgv_peak_bw: 51200
<7> [553.437221] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 1: max bw 38000 required 0 qgv_peak_bw: 51200
<7> [553.437343] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 51200
<7> [553.437461] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 51200
<7> [553.437580] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] Matching peaks QGV bw: 51200 for required data rate: 0
<7> [553.437715] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:149:pipe A] min cdclk: 256910 kHz -> 0 kHz
<7> [553.437926] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 32114 kHz -> 0 kHz
<7> [553.438064] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Can change cdclk via squashing
<7> [553.438198] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz
<7> [553.438319] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [553.438460] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:149:pipe A] enable: no [modeset]
<7> [553.438586] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:33:plane 1A] fb: [NOFB], visible: no
<7> [553.438710] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:63:plane 2A] fb: [NOFB], visible: no
<7> [553.438837] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:93:plane 3A] fb: [NOFB], visible: no
<7> [553.438985] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:123:plane 4A] fb: [NOFB], visible: no
<7> [553.439112] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:133:plane 5A] fb: [NOFB], visible: no
<7> [553.439240] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:143:cursor A] fb: [NOFB], visible: no
<7> [553.439419] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [553.440173] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [553.440366] i915 0000:00:02.0: [drm:intel_psr_disable_locked [i915]] Disabling PSR2
<7> [553.440664] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:505:DDI A/PHY A] PPS 0 turning VDD on
<7> [553.440899] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:505:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006f
<7> [553.441827] i915 0000:00:02.0: [drm:intel_edp_backlight_off [i915]]
<7> [553.650657] i915 0000:00:02.0: [drm:intel_backlight_set_pwm_level [i915]] [CONNECTOR:506:eDP-1] set backlight PWM = 0
<7> [553.651398] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [553.664994] i915 0000:00:02.0: [drm:intel_pps_off_unlocked [i915]] [ENCODER:505:DDI A/PHY A] PPS 0 turn panel power off
<7> [553.665471] i915 0000:00:02.0: [drm:intel_pps_off_unlocked [i915]] [ENCODER:505:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [553.665338] i915 0000:00:02.0: [drm:icp_irq_handler [i915]] hotplug event received, stat 0x00010000, dig 0x00000089, pins 0x00000010, long 0x00000000
<7> [553.665977] i915 0000:00:02.0: [drm:wait_panel_status [i915]] [ENCODER:505:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [553.666034] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] digital hpd on [ENCODER:505:DDI A/PHY A] - short
<7> [553.718012] i915 0000:00:02.0: [drm:icp_irq_handler [i915]] hotplug event received, stat 0x00010000, dig 0x0000008a, pins 0x00000010, long 0x00000010
<7> [553.718726] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] digital hpd on [ENCODER:505:DDI A/PHY A] - long
<7> [553.719295] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [553.725617] i915 0000:00:02.0: [drm:wait_panel_status [i915]] Wait complete
<7> [553.726102] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:149:pipe A]
<7> [553.726282] i915 0000:00:02.0: [drm:intel_dp_hpd_pulse [i915]] ignoring short hpd on eDP [ENCODER:505:DDI A/PHY A]
<7> [553.726912] i915 0000:00:02.0: [drm:intel_dp_hpd_pulse [i915]] ignoring long hpd on eDP [ENCODER:505:DDI A/PHY A]
<7> [553.727376] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [553.727924] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling AUX_A
<7> [553.728397] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:505:DDI A/PHY A]
<7> [553.728807] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:514:DDI B/PHY B]
<7> [553.729139] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:516:DP-MST A]
<7> [553.729463] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:517:DP-MST B]
<7> [553.729839] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:518:DP-MST C]
<7> [553.730169] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:519:DP-MST D]
<7> [553.730352] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:532:DDI TC1/PHY TC1]
<7> [553.730591] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:534:DP-MST A]
<7> [553.730859] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:535:DP-MST B]
<7> [553.731072] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:536:DP-MST C]
<7> [553.731294] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:537:DP-MST D]
<7> [553.731464] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:541:DDI TC2/PHY TC2]
<7> [553.731605] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:545:DDI TC3/PHY TC3]
<7> [553.731724] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:547:DP-MST A]
<7> [553.731838] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:548:DP-MST B]
<7> [553.731953] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:549:DP-MST C]
<7> [553.732070] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:550:DP-MST D]
<7> [553.732184] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:554:DDI TC4/PHY TC4]
<7> [553.732299] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:556:DP-MST A]
<7> [553.732415] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:557:DP-MST B]
<7> [553.732529] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:558:DP-MST C]
<7> [553.732665] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:559:DP-MST D]
<7> [553.732800] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:506:eDP-1]
<7> [553.732971] i915 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [i915]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [553.733138] i915 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [i915]] Changing mbus joined: yes -> no (pipe: *)
<7> [553.733279] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [553.733544] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_A
<7> [553.733786] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:149:pipe A]
<7> [553.733985] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Post changing CDCLK to 172800 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [553.734167] i915 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [i915]] aud_ts_cdclk set to M=60, N=432
<7> [553.734528] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:267:pipe B]
<7> [553.734706] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:385:pipe C]
<7> [553.734815] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:503:pipe D]
<7> [553.734920] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:149:pipe A]
<7> [553.734944] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:506:eDP-1]
<7> [553.734994] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:506:eDP-1] Limiting display bpp to 24 (EDID bpp 30, max requested bpp 24, max platform bpp 36)
<7> [553.735134] i915 0000:00:02.0: [drm:intel_dp_compute_config_limits [i915]] [ENCODER:505:DDI A/PHY A][CRTC:149:pipe A] DP link limits: pixel clock 513820 kHz DSC off max lanes 4 max rate 540000 max pipe_bpp 24 min link_bpp 18.0000 max link_bpp 24.0000
<7> [553.735257] i915 0000:00:02.0: [drm:intel_dp_compute_link_config [i915]] DP lane count 4 clock 432000 bpp input 24 compressed 0.0000 link rate required 1541460 available 1728000
<7> [553.735382] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:149:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [553.735513] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:505:DDI A/PHY A] [CRTC:149:pipe A]
<7> [553.735653] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [553.735795] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [553.735924] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [553.736054] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [553.736181] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 7483046/8388608 link 623587/524288)
<7> [553.736308] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [553.736437] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [553.736563] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [553.736728] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [553.736855] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)
<7> [553.736980] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)
<7> [553.737103] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)
<7> [553.737226] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)
<7> [553.737348] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [553.737471] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1801)
<7> [553.737595] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)
<7> [553.737746] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)
<7> [553.737879] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1878)
<7> [553.738004] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1878)
<7> [553.738128] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [553.738254] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [553.738378] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)
<7> [553.738505] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)
<7> [553.738628] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)
<7> [553.738772] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)
<7> [553.738896] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [553.739021] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1801)
<7> [553.739148] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)
<7> [553.739275] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)
<7> [553.739401] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1878)
<7> [553.739530] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1878)
<7> [553.739659] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [553.739818] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [553.739950] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [553.740074] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in enhanced_framing (expected no, found yes)
<7> [553.740201] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [553.740324] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 513820)
<7> [553.740449] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 513820)
<7> [553.740572] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in port_clock (expected 0, found 432000)
<7> [553.740705] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:149:pipe A] fastset requirement not met in set_context_latency (expected 0, found 1)
<7> [553.740844] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:149:pipe A] fastset requirement not met, forcing full modeset
<7> [553.741003] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [553.741128] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:149:pipe A] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x0 -> 0x1
<7> [553.741284] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:33:plane 1A] ddb ( 0 - 0) -> ( 0 - 4001), size 0 -> 4001
<7> [553.741408] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:143:cursor A] ddb ( 0 - 0) -> (4001 - 4096), size 0 -> 95
<7> [553.741526] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33: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> [553.741647] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 16, 16, 19, 19, 28, 0, 0, 0, 4, 0
<7> [553.741785] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 92, 365, 365, 434, 434, 638, 0, 0, 197, 92, 197
<7> [553.741909] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 183, 456, 456, 547, 547, 729, 0, 0, 198, 183, 198
<7> [553.742045] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:149:pipe A] data rate 2055280 num active planes 1
<7> [553.742177] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 28913 required 2159 qgv_peak_bw: 51200
<7> [553.742301] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 1: max bw 38000 required 2159 qgv_peak_bw: 51200
<7> [553.742423] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 2: max bw 38000 required 2159 qgv_peak_bw: 51200
<7> [553.742545] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 3: max bw 38000 required 2159 qgv_peak_bw: 51200
<7> [553.742699] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] Matching peaks QGV bw: 51200 for required data rate: 2159
<7> [553.742857] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:149:pipe A] min cdclk: 0 kHz -> 256910 kHz
<7> [553.743003] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 32114 kHz
<7> [553.743131] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Can change cdclk via squashing
<7> [553.743254] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 307200 kHz, actual 307200 kHz
<7> [553.743379] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [553.743523] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:149:pipe A] allocated DPLL 0
<7> [553.743652] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:149:pipe A] reserving DPLL 0
<7> [553.743860] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:149:pipe A] enable: yes [modeset]
<7> [553.743990] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [553.744119] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [553.744250] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [553.744384] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [553.744522] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [553.744654] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [553.744822] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dp m_n: lanes: 4; data_m: 7483046, data_n: 8388608, link_m: 623587, link_n: 524288, tu: 64
<7> [553.744950] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dp m2_n2: lanes: 4; data_m: 0, data_n: 0, link_m: 0, link_n: 0, tu: 0
<7> [553.745077] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] fec: disabled, enhanced framing: enabled
<7> [553.745202] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sdp split: disabled
<7> [553.745328] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled
<7> [553.745452] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] minimum HBlank: 0
<7> [553.745576] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [553.745699] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] DP SDP: VSC, revision 4, length 14
<7> [553.745843] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pixelformat: RGB
<7> [553.745971] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] colorimetry: sRGB
<7> [553.746100] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] bpc: 0
<7> [553.746225] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dynamic range: VESA range
<7> [553.746352] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] content type: Not defined
<7> [553.746482] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1
<7> [553.746604] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 1
<7> [553.746725] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1878, vmax: 1878, flipline: 1878, pipeline full: 0, guardband: 76 vsync start: 75, vsync end: 69
<7> [553.746872] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1802, vmax vblank: 1802, vmin vtotal: 1878, vmax vtotal: 1878
<7> [553.747001] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [553.747127] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "2880x1800": 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x48 0xa
<7> [553.747249] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "2880x1800": 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x48 0xa
<7> [553.747372] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=513820, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1801-1878 vs=1803-1809 vt=1878, flags=0xa
<7> [553.747494] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "2880x1800": 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x40 0xa
<7> [553.747615] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=513820, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1801-1878 vs=1803-1809 vt=1878, flags=0xa
<7> [553.747736] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 432000, pipe src: 2880x1800+0+0, pixel rate 513820, min cdclk 256910
<7> [553.747880] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 48, ips linetime: 0
<7> [553.748009] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [553.748138] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [553.748262] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [553.748386] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: yes, tbt_mode: no
<7> [553.748513] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] c10pll_hw_state: clock: 432000, fracen: no,
<7> [553.748635] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] multiplier: 225, tx_clk_div: 1.
<7> [553.748756] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] c10pll_rawhw_state:
<7> [553.748895] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] tx: 0x10, cmn: 0x21
<7> [553.749024] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1
<7> [553.749152] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [553.749277] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [553.749401] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [553.749534] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23
<7> [553.749662] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [553.749788] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [553.749930] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [553.750057] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [553.750182] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [553.750304] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [553.750426] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [553.750550] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [553.750672] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [553.750804] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [553.750959] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [553.751087] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [553.751215] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [553.751341] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:33:plane 1A] fb: [FB:563] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [553.751468] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [553.751598] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [553.751720] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:63:plane 2A] fb: [NOFB], visible: no
<7> [553.751854] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:93:plane 3A] fb: [NOFB], visible: no
<7> [553.751984] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:123:plane 4A] fb: [NOFB], visible: no
<7> [553.752110] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:133:plane 5A] fb: [NOFB], visible: no
<7> [553.752231] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:143:cursor A] fb: [NOFB], visible: no
<7> [553.752459] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_A
<7> [553.752674] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 307200 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [553.752839] i915 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [i915]] aud_ts_cdclk set to M=60, N=768
<7> [553.752998] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:505:DDI A/PHY A]
<7> [553.753128] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:514:DDI B/PHY B]
<7> [553.753256] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:516:DP-MST A]
<7> [553.753381] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:517:DP-MST B]
<7> [553.753504] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:518:DP-MST C]
<7> [553.753625] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:519:DP-MST D]
<7> [553.753745] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:532:DDI TC1/PHY TC1]
<7> [553.753873] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:534:DP-MST A]
<7> [553.753997] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:535:DP-MST B]
<7> [553.754119] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:536:DP-MST C]
<7> [553.754241] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:537:DP-MST D]
<7> [553.754360] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:541:DDI TC2/PHY TC2]
<7> [553.754477] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:545:DDI TC3/PHY TC3]
<7> [553.754596] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:547:DP-MST A]
<7> [553.754714] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:548:DP-MST B]
<7> [553.754831] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:549:DP-MST C]
<7> [553.754971] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:550:DP-MST D]
<7> [553.755098] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:554:DDI TC4/PHY TC4]
<7> [553.755220] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:556:DP-MST A]
<7> [553.755339] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:557:DP-MST B]
<7> [553.755457] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:558:DP-MST C]
<7> [553.755576] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:559:DP-MST D]
<7> [553.755727] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0xf
<7> [553.756025] i915 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [i915]] Changing mbus joined: no -> yes (pipe: *)
<7> [553.756194] i915 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [i915]] Updating dbuf ratio to 4 (mbus joined: yes)
<7> [553.756432] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling AUX_A
<7> [553.757630] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:149:pipe A]
<7> [553.757791] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [553.759263] i915 0000:00:02.0: [drm:intel_pps_on_unlocked [i915]] [ENCODER:505:DDI A/PHY A] PPS 0 turn panel power on
<7> [553.759407] i915 0000:00:02.0: [drm:wait_panel_power_cycle [i915]] [ENCODER:505:DDI A/PHY A] PPS 0 wait for panel power cycle (468 ms remaining)
<7> [554.242294] i915 0000:00:02.0: [drm:wait_panel_status [i915]] [ENCODER:505:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [554.252879] i915 0000:00:02.0: [drm:wait_panel_status [i915]] Wait complete
<7> [554.253399] i915 0000:00:02.0: [drm:intel_pps_on_unlocked [i915]] [ENCODER:505:DDI A/PHY A] PPS 0 wait for panel power on
<7> [554.253861] i915 0000:00:02.0: [drm:wait_panel_status [i915]] [ENCODER:505:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [554.285583] i915 0000:00:02.0: [drm:icp_irq_handler [i915]] hotplug event received, stat 0x00010000, dig 0x0000008a, pins 0x00000010, long 0x00000010
<7> [554.286265] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] digital hpd on [ENCODER:505:DDI A/PHY A] - long
<7> [554.286837] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [554.287542] i915 0000:00:02.0: [drm:intel_dp_hpd_pulse [i915]] ignoring long hpd on eDP [ENCODER:505:DDI A/PHY A]
<7> [554.463071] i915 0000:00:02.0: [drm:wait_panel_status [i915]] Wait complete
<7> [554.463911] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:505:DDI A/PHY A] PPS 0 turning VDD on
<7> [554.464474] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:505:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [554.469611] i915 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX A/DDI A/PHY A: DPCD: 14 14 c4 41 00 00 01 c0 02 00 00 00 00 0b 80
<7> [554.470622] i915 0000:00:02.0: [drm:intel_dp_start_link_train [i915]] [CONNECTOR:506:eDP-1][ENCODER:505:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [554.471384] i915 0000:00:02.0: [drm:intel_dp_start_link_train [i915]] [CONNECTOR:506:eDP-1][ENCODER:505:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 06
<7> [554.472705] i915 0000:00:02.0: [drm:intel_dp_set_signal_levels [i915]] [CONNECTOR:506:eDP-1][ENCODER:505:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [554.473588] i915 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [i915]] [CONNECTOR:506:eDP-1][ENCODER:505:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [554.474730] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:506:eDP-1][ENCODER:505:DDI A/PHY A][DPRX] Clock recovery OK
<7> [554.474968] i915 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [i915]] [CONNECTOR:506:eDP-1][ENCODER:505:DDI A/PHY A][DPRX] Using DP training pattern TPS3
<7> [554.476494] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:506:eDP-1][ENCODER:505:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [554.476737] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:506:eDP-1][ENCODER:505:DDI A/PHY A][DPRX] Link Training passed at link rate = 432000, lane count = 4
<7> [554.477597] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [554.478076] i915 0000:00:02.0: [drm:intel_edp_backlight_on [i915]]
<7> [554.478302] i915 0000:00:02.0: [drm:intel_backlight_enable [i915]] pipe A
<7> [554.478590] i915 0000:00:02.0: [drm:intel_backlight_set_pwm_level [i915]] [CONNECTOR:506:eDP-1] set backlight PWM = 13892
<7> [554.489281] i915 0000:00:02.0: [drm:intel_psr_post_plane_update [i915]] Enabling PSR2
<7> [554.491301] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:506:eDP-1]
<7> [554.491747] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:149:pipe A]
<7> [557.078482] i915 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [i915]] [ENCODER:505:DDI A/PHY A] PPS 0 turning VDD off
<7> [557.079175] i915 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [i915]] [ENCODER:505:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x00000067
<7> [557.079906] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [557.080681] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [557.081322] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [557.134728] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [557.135233] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [557.163095] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [557.163583] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [557.164063] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [557.246134] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [557.246752] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [557.274058] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [557.274549] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [557.275041] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [557.357353] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [557.357919] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [557.384924] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [557.385405] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [557.385885] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [557.468366] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [557.468919] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [557.495689] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [557.496212] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [557.496690] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [557.579360] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [557.579849] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [557.607600] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [557.608074] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [557.608569] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [557.690482] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [557.690975] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [557.718466] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [557.718981] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [557.719477] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [557.801405] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [557.801867] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [557.829322] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [557.829821] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [557.830287] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [557.912727] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [557.913310] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [557.940167] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [557.940647] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [557.941117] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [558.023848] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [558.024339] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [558.051960] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [558.052456] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [558.052935] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [558.135156] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [558.135775] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [558.162889] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [558.163381] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [558.163850] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [558.245351] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [558.245836] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [558.273578] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [558.274061] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [558.274486] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [558.356316] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [558.356486] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [558.384422] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [558.384925] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [558.385398] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [558.468454] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [558.469071] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [558.496197] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [558.496676] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [558.497141] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [558.579460] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [558.580033] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [558.606902] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [558.607381] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [558.607876] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [558.689987] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [558.690491] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [558.718712] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [558.719235] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [558.719715] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [558.801712] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [558.802242] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [558.829433] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [558.829991] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [558.830507] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [558.912599] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [558.913200] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [558.940122] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [558.940653] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [558.941114] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [559.023705] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [559.024200] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [559.051942] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [559.052455] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [559.052945] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [559.135401] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [559.135995] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [559.162728] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [559.163262] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [559.163778] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [559.246185] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [559.246660] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [559.274441] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [559.275053] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [559.275553] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [559.357285] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [559.357867] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [559.385101] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [559.385641] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [559.386129] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [559.468315] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [559.468980] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [559.495801] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [559.496407] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [559.496900] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [559.579512] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [559.580045] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [559.607339] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [559.607877] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [559.608369] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [559.690855] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [559.691345] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [559.718017] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [559.718499] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [559.718953] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [559.801577] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [559.802061] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [559.829672] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [559.830194] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [559.830653] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [559.912888] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [559.913503] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [559.940354] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [559.940936] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [559.941450] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [560.023914] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [560.024466] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [560.052042] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [560.052532] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [560.053008] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [560.135081] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [560.135709] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [560.162715] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [560.163201] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [560.163687] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [560.246191] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [560.246772] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [560.275311] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [560.275841] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [560.276320] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [560.357020] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [560.357556] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [560.385990] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [560.386532] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [560.387041] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [560.468410] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [560.468912] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [560.495479] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [560.495960] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [560.496388] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [560.579500] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [560.580280] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [560.607094] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [560.607624] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [560.608081] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [560.690562] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [560.691031] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [560.718685] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [560.719207] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [560.719686] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [560.801692] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [560.802222] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [560.829240] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [560.829772] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [560.830233] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [560.912478] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [560.913006] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [560.940866] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [560.941379] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [560.941855] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [561.023654] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [561.024115] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [561.051425] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [561.051904] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [561.052388] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [561.135090] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [561.135626] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [561.163033] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [561.163502] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [561.163934] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [561.246168] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [561.246775] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [561.273580] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [561.274127] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [561.274625] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [561.357022] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [561.357530] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [561.385092] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [561.385589] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [561.386013] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [561.468343] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [561.468922] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [561.495634] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [561.496163] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [561.496649] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [561.579308] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [561.579820] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [561.607174] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [561.607770] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [561.608328] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [561.690637] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [561.691122] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [561.719714] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [561.720246] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [561.720709] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [561.801694] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [561.802255] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [561.829197] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [561.829702] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [561.830162] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [561.912882] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [561.913383] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [561.941734] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [561.942215] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [561.942640] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [562.023988] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [562.024511] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [562.051234] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [562.051735] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [562.052152] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [562.135018] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [562.135516] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [562.162803] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [562.163457] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [562.164006] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<6> [562.398222] [IGT] kms_flip: finished subtest A-eDP1, FAIL
Created at 2026-01-12 20:23:29