Results for igt@kms_flip@flip-vs-expired-vblank-interruptible@d-edp1

Result: Fail

integration-manifest git-log-oneline i915_display_info4 igt_runner4 runtimes4 results4.json results4-i915-load.json guc_logs4.tar i915_display_info_post_exec4 boot4 dmesg4

DetailValue
Duration 3.94 seconds
Hostname 
shard-mtlp-5
Igt-Version 
IGT-Version: 2.4-g02b0e01dd (x86_64) (Linux: 7.1.0-rc1-Patchwork_165719v1-g1db870ffb55b+ x86_64)
Out 
Starting dynamic subtest: D-eDP1
  2880x1800: 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x48 0xa 
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
  #2 ../tests/kms_flip.c:1920 run_test()
  #3 ../tests/kms_flip.c:2220 __igt_unique____real_main2105()
  #4 ../tests/kms_flip.c:2105 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest D-eDP1: FAIL (3.943s)
Subtest flip-vs-expired-vblank-interruptible: FAIL (29.890s)
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../lib/igt_kms.c:2352 kmstest_set_connector_dpms()
  #2 ../lib/igt_core.c:2961 igt_atexit_handler()
  #3 [erand48_r+0x1d6]
  #4 [exit+0x1e]
  #5 ../lib/igt_core.c:2416 igt_exit()
  #6 [main+0x3f]
  #7 [__libc_init_first+0x8a]
  #8 [__libc_start_main+0x8b]
  #9 [_start+0x25]
Err 
Starting dynamic subtest: D-eDP1
(kms_flip:2698) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
(kms_flip:2698) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2698) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest D-eDP1 failed.
**** DEBUG ****
(kms_flip:2698) DEBUG: Vblank took 4us
(kms_flip:2698) DEBUG: name = flip
last_ts = 362.962097
last_received_ts = 362.961761
last_seq = 412
current_ts = 362.973206
current_received_ts = 362.972900
current_seq = 413
count = 120
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 6us
(kms_flip:2698) DEBUG: name = flip
last_ts = 362.973206
last_received_ts = 362.972900
last_seq = 413
current_ts = 362.984314
current_received_ts = 362.984070
current_seq = 414
count = 121
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 362.984314
last_received_ts = 362.984070
last_seq = 414
current_ts = 362.995422
current_received_ts = 362.995117
current_seq = 415
count = 122
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 6us
(kms_flip:2698) DEBUG: name = flip
last_ts = 362.995422
last_received_ts = 362.995117
last_seq = 415
current_ts = 363.006531
current_received_ts = 363.006226
current_seq = 416
count = 123
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.006531
last_received_ts = 363.006226
last_seq = 416
current_ts = 363.017639
current_received_ts = 363.017334
current_seq = 417
count = 124
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.017639
last_received_ts = 363.017334
last_seq = 417
current_ts = 363.028748
current_received_ts = 363.028442
current_seq = 418
count = 125
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 4us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.028748
last_received_ts = 363.028442
last_seq = 418
current_ts = 363.039856
current_received_ts = 363.039520
current_seq = 419
count = 126
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 4us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.039856
last_received_ts = 363.039520
last_seq = 419
current_ts = 363.050964
current_received_ts = 363.050659
current_seq = 420
count = 127
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.050964
last_received_ts = 363.050659
last_seq = 420
current_ts = 363.062073
current_received_ts = 363.061798
current_seq = 421
count = 128
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.062073
last_received_ts = 363.061798
last_seq = 421
current_ts = 363.073181
current_received_ts = 363.072876
current_seq = 422
count = 129
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 4us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.073181
last_received_ts = 363.072876
last_seq = 422
current_ts = 363.084290
current_received_ts = 363.083984
current_seq = 423
count = 130
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.084290
last_received_ts = 363.083984
last_seq = 423
current_ts = 363.095398
current_received_ts = 363.095062
current_seq = 424
count = 131
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 4us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.095398
last_received_ts = 363.095062
last_seq = 424
current_ts = 363.106506
current_received_ts = 363.106171
current_seq = 425
count = 132
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 6us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.106506
last_received_ts = 363.106171
last_seq = 425
current_ts = 363.117615
current_received_ts = 363.117310
current_seq = 426
count = 133
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.117615
last_received_ts = 363.117310
last_seq = 426
current_ts = 363.128723
current_received_ts = 363.128418
current_seq = 427
count = 134
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.128723
last_received_ts = 363.128418
last_seq = 427
current_ts = 363.139832
current_received_ts = 363.139526
current_seq = 428
count = 135
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.139832
last_received_ts = 363.139526
last_seq = 428
current_ts = 363.150940
current_received_ts = 363.150635
current_seq = 429
count = 136
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.150940
last_received_ts = 363.150635
last_seq = 429
current_ts = 363.162048
current_received_ts = 363.161774
current_seq = 430
count = 137
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.162048
last_received_ts = 363.161774
last_seq = 430
current_ts = 363.173157
current_received_ts = 363.172882
current_seq = 431
count = 138
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.173157
last_received_ts = 363.172882
last_seq = 431
current_ts = 363.184265
current_received_ts = 363.183990
current_seq = 432
count = 139
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.184265
last_received_ts = 363.183990
last_seq = 432
current_ts = 363.195374
current_received_ts = 363.195068
current_seq = 433
count = 140
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.195374
last_received_ts = 363.195068
last_seq = 433
current_ts = 363.206482
current_received_ts = 363.206207
current_seq = 434
count = 141
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.206482
last_received_ts = 363.206207
last_seq = 434
current_ts = 363.217590
current_received_ts = 363.217285
current_seq = 435
count = 142
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 4us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.217590
last_received_ts = 363.217285
last_seq = 435
current_ts = 363.228699
current_received_ts = 363.228363
current_seq = 436
count = 143
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 7us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.228699
last_received_ts = 363.228363
last_seq = 436
current_ts = 363.239807
current_received_ts = 363.239502
current_seq = 437
count = 144
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.239807
last_received_ts = 363.239502
last_seq = 437
current_ts = 363.250916
current_received_ts = 363.250641
current_seq = 438
count = 145
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.250916
last_received_ts = 363.250641
last_seq = 438
current_ts = 363.262024
current_received_ts = 363.261719
current_seq = 439
count = 146
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.262024
last_received_ts = 363.261719
last_seq = 439
current_ts = 363.273132
current_received_ts = 363.272858
current_seq = 440
count = 147
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 6us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.273132
last_received_ts = 363.272858
last_seq = 440
current_ts = 363.284241
current_received_ts = 363.283966
current_seq = 441
count = 148
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 4us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.284241
last_received_ts = 363.283966
last_seq = 441
current_ts = 363.295349
current_received_ts = 363.295044
current_seq = 442
count = 149
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.295349
last_received_ts = 363.295044
last_seq = 442
current_ts = 363.306458
current_received_ts = 363.306183
current_seq = 443
count = 150
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 4us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.306458
last_received_ts = 363.306183
last_seq = 443
current_ts = 363.317566
current_received_ts = 363.317230
current_seq = 444
count = 151
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 4us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.317566
last_received_ts = 363.317230
last_seq = 444
current_ts = 363.328674
current_received_ts = 363.328369
current_seq = 445
count = 152
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 8us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.328674
last_received_ts = 363.328369
last_seq = 445
current_ts = 363.339783
current_received_ts = 363.339478
current_seq = 446
count = 153
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.339783
last_received_ts = 363.339478
last_seq = 446
current_ts = 363.350891
current_received_ts = 363.350586
current_seq = 447
count = 154
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.350891
last_received_ts = 363.350586
last_seq = 447
current_ts = 363.362000
current_received_ts = 363.361725
current_seq = 448
count = 155
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.362000
last_received_ts = 363.361725
last_seq = 448
current_ts = 363.373108
current_received_ts = 363.372894
current_seq = 449
count = 156
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 6us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.373108
last_received_ts = 363.372894
last_seq = 449
current_ts = 363.384216
current_received_ts = 363.383911
current_seq = 450
count = 157
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.384216
last_received_ts = 363.383911
last_seq = 450
current_ts = 363.395325
current_received_ts = 363.395081
current_seq = 451
count = 158
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 4us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.395325
last_received_ts = 363.395081
last_seq = 451
current_ts = 363.406433
current_received_ts = 363.406128
current_seq = 452
count = 159
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.406433
last_received_ts = 363.406128
last_seq = 452
current_ts = 363.417542
current_received_ts = 363.417236
current_seq = 453
count = 160
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 4us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.417542
last_received_ts = 363.417236
last_seq = 453
current_ts = 363.428650
current_received_ts = 363.428345
current_seq = 454
count = 161
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 4us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.428650
last_received_ts = 363.428345
last_seq = 454
current_ts = 363.439758
current_received_ts = 363.439453
current_seq = 455
count = 162
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 4us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.439758
last_received_ts = 363.439453
last_seq = 455
current_ts = 363.450897
current_received_ts = 363.450592
current_seq = 456
count = 163
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 8us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.450897
last_received_ts = 363.450592
last_seq = 456
current_ts = 363.461975
current_received_ts = 363.461700
current_seq = 457
count = 164
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.461975
last_received_ts = 363.461700
last_seq = 457
current_ts = 363.473083
current_received_ts = 363.472778
current_seq = 458
count = 165
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.473083
last_received_ts = 363.472778
last_seq = 458
current_ts = 363.484222
current_received_ts = 363.483917
current_seq = 459
count = 166
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.484222
last_received_ts = 363.483917
last_seq = 459
current_ts = 363.495300
current_received_ts = 363.494995
current_seq = 460
count = 167
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 7us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.495300
last_received_ts = 363.494995
last_seq = 460
current_ts = 363.506409
current_received_ts = 363.506104
current_seq = 461
count = 168
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 4us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.506409
last_received_ts = 363.506104
last_seq = 461
current_ts = 363.517517
current_received_ts = 363.517212
current_seq = 462
count = 169
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 4us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.517517
last_received_ts = 363.517212
last_seq = 462
current_ts = 363.528656
current_received_ts = 363.528351
current_seq = 463
count = 170
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 6us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.528656
last_received_ts = 363.528351
last_seq = 463
current_ts = 363.539734
current_received_ts = 363.539459
current_seq = 464
count = 171
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 4us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.539734
last_received_ts = 363.539459
last_seq = 464
current_ts = 363.550873
current_received_ts = 363.550568
current_seq = 465
count = 172
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.550873
last_received_ts = 363.550568
last_seq = 465
current_ts = 363.561981
current_received_ts = 363.561707
current_seq = 466
count = 173
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 8us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.561981
last_received_ts = 363.561707
last_seq = 466
current_ts = 363.573090
current_received_ts = 363.572754
current_seq = 467
count = 174
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.573090
last_received_ts = 363.572754
last_seq = 467
current_ts = 363.584198
current_received_ts = 363.583893
current_seq = 468
count = 175
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.584198
last_received_ts = 363.583893
last_seq = 468
current_ts = 363.595306
current_received_ts = 363.595062
current_seq = 469
count = 176
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 14us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.595306
last_received_ts = 363.595062
last_seq = 469
current_ts = 363.606415
current_received_ts = 363.606079
current_seq = 470
count = 177
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 6us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.606415
last_received_ts = 363.606079
last_seq = 470
current_ts = 363.617523
current_received_ts = 363.617188
current_seq = 471
count = 178
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.617523
last_received_ts = 363.617188
last_seq = 471
current_ts = 363.628632
current_received_ts = 363.628357
current_seq = 472
count = 179
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 6us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.628632
last_received_ts = 363.628357
last_seq = 472
current_ts = 363.639740
current_received_ts = 363.639435
current_seq = 473
count = 180
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.639740
last_received_ts = 363.639435
last_seq = 473
current_ts = 363.650848
current_received_ts = 363.650543
current_seq = 474
count = 181
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 4us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.650848
last_received_ts = 363.650543
last_seq = 474
current_ts = 363.661957
current_received_ts = 363.661652
current_seq = 475
count = 182
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 12us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.661957
last_received_ts = 363.661652
last_seq = 475
current_ts = 363.673065
current_received_ts = 363.672760
current_seq = 476
count = 183
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.673065
last_received_ts = 363.672760
last_seq = 476
current_ts = 363.684174
current_received_ts = 363.683868
current_seq = 477
count = 184
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 7us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.684174
last_received_ts = 363.683868
last_seq = 477
current_ts = 363.695282
current_received_ts = 363.694977
current_seq = 478
count = 185
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 4us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.695282
last_received_ts = 363.694977
last_seq = 478
current_ts = 363.706390
current_received_ts = 363.706085
current_seq = 479
count = 186
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.706390
last_received_ts = 363.706085
last_seq = 479
current_ts = 363.717499
current_received_ts = 363.717163
current_seq = 480
count = 187
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 4us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.717499
last_received_ts = 363.717163
last_seq = 480
current_ts = 363.728607
current_received_ts = 363.728271
current_seq = 481
count = 188
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 4us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.728607
last_received_ts = 363.728271
last_seq = 481
current_ts = 363.739716
current_received_ts = 363.739410
current_seq = 482
count = 189
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.739716
last_received_ts = 363.739410
last_seq = 482
current_ts = 363.750824
current_received_ts = 363.750549
current_seq = 483
count = 190
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 14us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.750824
last_received_ts = 363.750549
last_seq = 483
current_ts = 363.761932
current_received_ts = 363.761627
current_seq = 484
count = 191
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 4us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.761932
last_received_ts = 363.761627
last_seq = 484
current_ts = 363.773041
current_received_ts = 363.772736
current_seq = 485
count = 192
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.773041
last_received_ts = 363.772736
last_seq = 485
current_ts = 363.784149
current_received_ts = 363.783844
current_seq = 486
count = 193
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.784149
last_received_ts = 363.783844
last_seq = 486
current_ts = 363.795258
current_received_ts = 363.794922
current_seq = 487
count = 194
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 6us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.795258
last_received_ts = 363.794922
last_seq = 487
current_ts = 363.806366
current_received_ts = 363.806061
current_seq = 488
count = 195
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.806366
last_received_ts = 363.806061
last_seq = 488
current_ts = 363.817474
current_received_ts = 363.817169
current_seq = 489
count = 196
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 14us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.817474
last_received_ts = 363.817169
last_seq = 489
current_ts = 363.828583
current_received_ts = 363.828247
current_seq = 490
count = 197
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 4us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.828583
last_received_ts = 363.828247
last_seq = 490
current_ts = 363.839691
current_received_ts = 363.839417
current_seq = 491
count = 198
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 6us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.839691
last_received_ts = 363.839417
last_seq = 491
current_ts = 363.850800
current_received_ts = 363.850494
current_seq = 492
count = 199
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 10us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.850800
last_received_ts = 363.850494
last_seq = 492
current_ts = 363.861908
current_received_ts = 363.861633
current_seq = 493
count = 200
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.861908
last_received_ts = 363.861633
last_seq = 493
current_ts = 363.873016
current_received_ts = 363.872742
current_seq = 494
count = 201
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 4us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.873016
last_received_ts = 363.872742
last_seq = 494
current_ts = 363.884125
current_received_ts = 363.883881
current_seq = 495
count = 202
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.884125
last_received_ts = 363.883881
last_seq = 495
current_ts = 363.895233
current_received_ts = 363.894928
current_seq = 496
count = 203
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.895233
last_received_ts = 363.894928
last_seq = 496
current_ts = 363.906342
current_received_ts = 363.906036
current_seq = 497
count = 204
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.906342
last_received_ts = 363.906036
last_seq = 497
current_ts = 363.917450
current_received_ts = 363.917145
current_seq = 498
count = 205
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 4us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.917450
last_received_ts = 363.917145
last_seq = 498
current_ts = 363.928558
current_received_ts = 363.928223
current_seq = 499
count = 206
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.928558
last_received_ts = 363.928223
last_seq = 499
current_ts = 363.939667
current_received_ts = 363.939362
current_seq = 500
count = 207
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 4us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.939667
last_received_ts = 363.939362
last_seq = 500
current_ts = 363.950775
current_received_ts = 363.950500
current_seq = 501
count = 208
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 4us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.950775
last_received_ts = 363.950500
last_seq = 501
current_ts = 363.961884
current_received_ts = 363.961639
current_seq = 502
count = 209
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.961884
last_received_ts = 363.961639
last_seq = 502
current_ts = 363.972992
current_received_ts = 363.972687
current_seq = 503
count = 210
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.972992
last_received_ts = 363.972687
last_seq = 503
current_ts = 363.984100
current_received_ts = 363.983795
current_seq = 504
count = 211
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 6us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.984100
last_received_ts = 363.983795
last_seq = 504
current_ts = 363.995209
current_received_ts = 363.994873
current_seq = 505
count = 212
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 363.995209
last_received_ts = 363.994873
last_seq = 505
current_ts = 364.006317
current_received_ts = 364.006042
current_seq = 506
count = 213
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 364.006317
last_received_ts = 364.006042
last_seq = 506
current_ts = 364.017426
current_received_ts = 364.017120
current_seq = 507
count = 214
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 4us
(kms_flip:2698) DEBUG: name = flip
last_ts = 364.017426
last_received_ts = 364.017120
last_seq = 507
current_ts = 364.028534
current_received_ts = 364.028198
current_seq = 508
count = 215
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 364.028534
last_received_ts = 364.028198
last_seq = 508
current_ts = 364.039642
current_received_ts = 364.039368
current_seq = 509
count = 216
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 364.039642
last_received_ts = 364.039368
last_seq = 509
current_ts = 364.050781
current_received_ts = 364.050476
current_seq = 510
count = 217
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 364.050781
last_received_ts = 364.050476
last_seq = 510
current_ts = 364.061890
current_received_ts = 364.061554
current_seq = 511
count = 218
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 364.061890
last_received_ts = 364.061554
last_seq = 511
current_ts = 364.072968
current_received_ts = 364.072693
current_seq = 512
count = 219
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 15us
(kms_flip:2698) DEBUG: name = flip
last_ts = 364.072968
last_received_ts = 364.072693
last_seq = 512
current_ts = 364.084076
current_received_ts = 364.083801
current_seq = 513
count = 220
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 4us
(kms_flip:2698) DEBUG: name = flip
last_ts = 364.084076
last_received_ts = 364.083801
last_seq = 513
current_ts = 364.095215
current_received_ts = 364.094940
current_seq = 514
count = 221
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 364.095215
last_received_ts = 364.094940
last_seq = 514
current_ts = 364.106323
current_received_ts = 364.106049
current_seq = 515
count = 222
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 4us
(kms_flip:2698) DEBUG: name = flip
last_ts = 364.106323
last_received_ts = 364.106049
last_seq = 515
current_ts = 364.117432
current_received_ts = 364.117096
current_seq = 516
count = 223
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 364.117432
last_received_ts = 364.117096
last_seq = 516
current_ts = 364.128540
current_received_ts = 364.128235
current_seq = 517
count = 224
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 11us
(kms_flip:2698) DEBUG: name = flip
last_ts = 364.128540
last_received_ts = 364.128235
last_seq = 517
current_ts = 364.139648
current_received_ts = 364.139313
current_seq = 518
count = 225
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 8us
(kms_flip:2698) DEBUG: name = flip
last_ts = 364.139648
last_received_ts = 364.139313
last_seq = 518
current_ts = 364.150757
current_received_ts = 364.150482
current_seq = 519
count = 226
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 7us
(kms_flip:2698) DEBUG: name = flip
last_ts = 364.150757
last_received_ts = 364.150482
last_seq = 519
current_ts = 364.161865
current_received_ts = 364.161530
current_seq = 520
count = 227
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 6us
(kms_flip:2698) DEBUG: name = flip
last_ts = 364.161865
last_received_ts = 364.161530
last_seq = 520
current_ts = 364.172974
current_received_ts = 364.172668
current_seq = 521
count = 228
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 8us
(kms_flip:2698) DEBUG: name = flip
last_ts = 364.172974
last_received_ts = 364.172668
last_seq = 521
current_ts = 364.184082
current_received_ts = 364.183746
current_seq = 522
count = 229
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 6us
(kms_flip:2698) DEBUG: name = flip
last_ts = 364.184082
last_received_ts = 364.183746
last_seq = 522
current_ts = 364.195190
current_received_ts = 364.194885
current_seq = 523
count = 230
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 6us
(kms_flip:2698) DEBUG: name = flip
last_ts = 364.195190
last_received_ts = 364.194885
last_seq = 523
current_ts = 364.206299
current_received_ts = 364.205963
current_seq = 524
count = 231
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 23us
(kms_flip:2698) DEBUG: name = flip
last_ts = 364.206299
last_received_ts = 364.205963
last_seq = 524
current_ts = 364.217407
current_received_ts = 364.217072
current_seq = 525
count = 232
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 4us
(kms_flip:2698) DEBUG: name = flip
last_ts = 364.217407
last_received_ts = 364.217072
last_seq = 525
current_ts = 364.228516
current_received_ts = 364.228210
current_seq = 526
count = 233
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 364.228516
last_received_ts = 364.228210
last_seq = 526
current_ts = 364.239624
current_received_ts = 364.239288
current_seq = 527
count = 234
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 364.239624
last_received_ts = 364.239288
last_seq = 527
current_ts = 364.250732
current_received_ts = 364.250427
current_seq = 528
count = 235
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 364.250732
last_received_ts = 364.250427
last_seq = 528
current_ts = 364.261841
current_received_ts = 364.261566
current_seq = 529
count = 236
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 4us
(kms_flip:2698) DEBUG: name = flip
last_ts = 364.261841
last_received_ts = 364.261566
last_seq = 529
current_ts = 364.272949
current_received_ts = 364.272644
current_seq = 530
count = 237
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 364.272949
last_received_ts = 364.272644
last_seq = 530
current_ts = 364.284058
current_received_ts = 364.283752
current_seq = 531
count = 238
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 6us
(kms_flip:2698) DEBUG: name = flip
last_ts = 364.284058
last_received_ts = 364.283752
last_seq = 531
current_ts = 364.295166
current_received_ts = 364.294861
current_seq = 532
count = 239
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 5us
(kms_flip:2698) DEBUG: name = flip
last_ts = 364.295166
last_received_ts = 364.294861
last_seq = 532
current_ts = 364.306274
current_received_ts = 364.305969
current_seq = 533
count = 240
seq_step = 1
(kms_flip:2698) DEBUG: Vblank took 956us
(kms_flip:2698) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
(kms_flip:2698) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2698) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:2698) igt_core-INFO: Stack trace:
(kms_flip:2698) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2698) igt_core-INFO:   #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
(kms_flip:2698) igt_core-INFO:   #2 ../tests/kms_flip.c:1920 run_test()
(kms_flip:2698) igt_core-INFO:   #3 ../tests/kms_flip.c:2220 __igt_unique____real_main2105()
(kms_flip:2698) igt_core-INFO:   #4 ../tests/kms_flip.c:2105 main()
(kms_flip:2698) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2698) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2698) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest D-eDP1: FAIL (3.943s)
Subtest flip-vs-expired-vblank-interruptible: FAIL (29.890s)
(kms_flip:2698) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2352:
(kms_flip:2698) igt_kms-CRITICAL: Failed assertion: found_it
(kms_flip:2698) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor
(kms_flip:2698) igt_kms-CRITICAL: DPMS property not found on 508
Dmesg
<6> [442.990812] [IGT] kms_flip: starting dynamic subtest D-eDP1
<7> [442.991338] i915 0000:00:02.0: [drm:i915_dpt_create [i915]] Allocating dpt from smem
<7> [442.991487] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:566]
<7> [442.991545] i915 0000:00:02.0: [drm:i915_dpt_create [i915]] Allocating dpt from smem
<7> [442.991676] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:580]
<7> [442.994390] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [442.994718] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [443.012216] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [443.012475] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [443.020911] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [443.021170] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [443.037760] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [443.038041] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [443.044749] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [443.044892] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe B]
<7> [443.044973] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]
<7> [443.045046] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.enable (expected yes, found no)
<7> [443.045201] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.active (expected yes, found no)
<7> [443.045350] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in cpu_transcoder (expected 2, found -1)
<7> [443.045498] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in lane_count (expected 4, found 0)
<7> [443.045635] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in dp_m_n (expected tu 64 data 7483046/8388608 link 498869/524288, found tu 0, data 0/0 link 0/0)
<7> [443.045772] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [443.045909] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [443.046055] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [443.046192] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [443.046328] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [443.046464] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [443.046600] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [443.046738] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [443.046893] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [443.047044] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1801, found 0)
<7> [443.047181] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [443.047317] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [443.047452] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1878, found 0)
<7> [443.047587] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1878, found 0)
<7> [443.047784] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [443.047920] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [443.048067] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [443.048204] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [443.048341] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [443.048476] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [443.048609] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [443.048744] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1801, found 0)
<7> [443.048882] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [443.049023] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [443.049159] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1878, found 0)
<7> [443.049295] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1878, found 0)
<7> [443.049429] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [443.049563] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [443.049697] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [443.049831] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in enhanced_framing (expected yes, found no)
<7> [443.049965] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [443.050110] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 513820, found 0)
<7> [443.050244] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 513820, found 0)
<7> [443.050376] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in port_clock (expected 540000, found 0)
<7> [443.050514] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in set_context_latency (expected 1, found 0)
<7> [443.050659] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:387:pipe C] fastset requirement not met, forcing full modeset
<7> [443.050795] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:387:pipe C] releasing DPLL 0
<7> [443.050952] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0xd -> 0x1 (total dbuf slices 0xf), mbus joined? no->no
<7> [443.051099] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:387:pipe C] dbuf slices 0xc -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x4 -> 0x0
<7> [443.051248] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:381:cursor C] ddb (1953 - 2048) -> ( 0 - 0), size 95 -> 0
<7> [443.051392] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:387:pipe C] min cdclk: 256910 kHz -> 0 kHz
<7> [443.051532] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Can change cdclk via squashing
<7> [443.051669] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz
<7> [443.051806] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [443.051971] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:387:pipe C] enable: no [modeset]
<7> [443.052172] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:271:plane 1C] fb: [NOFB], visible: no
<7> [443.052311] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:301:plane 2C] fb: [NOFB], visible: no
<7> [443.052446] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:331:plane 3C] fb: [NOFB], visible: no
<7> [443.052579] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:361:plane 4C] fb: [NOFB], visible: no
<7> [443.052713] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:371:plane 5C] fb: [NOFB], visible: no
<7> [443.052848] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:381:cursor C] fb: [NOFB], visible: no
<7> [443.053502] i915 0000:00:02.0: [drm:intel_edp_backlight_off [i915]]
<7> [443.258966] i915 0000:00:02.0: [drm:intel_backlight_set_pwm_level [i915]] [CONNECTOR:508:eDP-1] set backlight PWM = 0
<7> [443.259685] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe C
<7> [443.268050] i915 0000:00:02.0: [drm:intel_pps_off_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 turn panel power off
<7> [443.268336] i915 0000:00:02.0: [drm:icp_irq_handler [i915]] hotplug event received, stat 0x00010000, dig 0x00000089, pins 0x00000010, long 0x00000000
<7> [443.268572] i915 0000:00:02.0: [drm:intel_pps_off_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [443.268898] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] digital hpd on [ENCODER:507:DDI A/PHY A] - short
<7> [443.269189] i915 0000:00:02.0: [drm:wait_panel_status [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [443.321037] i915 0000:00:02.0: [drm:icp_irq_handler [i915]] hotplug event received, stat 0x00010000, dig 0x0000008a, pins 0x00000010, long 0x00000010
<7> [443.321599] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] digital hpd on [ENCODER:507:DDI A/PHY A] - long
<7> [443.322102] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [443.323819] i915 0000:00:02.0: [drm:wait_panel_status [i915]] Wait complete
<7> [443.324940] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x4, on? 1) for [CRTC:387:pipe C]
<7> [443.325096] i915 0000:00:02.0: [drm:intel_dp_hpd_pulse [i915]] ignoring short hpd on eDP [ENCODER:507:DDI A/PHY A]
<7> [443.325569] i915 0000:00:02.0: [drm:intel_dp_hpd_pulse [i915]] ignoring long hpd on eDP [ENCODER:507:DDI A/PHY A]
<7> [443.326771] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [443.327207] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling AUX_A
<7> [443.327577] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:507:DDI A/PHY A]
<7> [443.327867] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:516:DDI B/PHY B]
<7> [443.328088] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:518:DP-MST A]
<7> [443.328226] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:519:DP-MST B]
<7> [443.328370] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:520:DP-MST C]
<7> [443.328508] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:521:DP-MST D]
<7> [443.328652] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:534:DDI TC1/PHY TC1]
<7> [443.328825] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:536:DP-MST A]
<7> [443.329061] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:537:DP-MST B]
<7> [443.329278] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:538:DP-MST C]
<7> [443.329421] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:539:DP-MST D]
<7> [443.329553] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:543:DDI TC2/PHY TC2]
<7> [443.329682] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:547:DDI TC3/PHY TC3]
<7> [443.329829] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:549:DP-MST A]
<7> [443.329961] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:550:DP-MST B]
<7> [443.330189] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:551:DP-MST C]
<7> [443.330567] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:552:DP-MST D]
<7> [443.330869] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:556:DDI TC4/PHY TC4]
<7> [443.331116] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:558:DP-MST A]
<7> [443.331266] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:559:DP-MST B]
<7> [443.331402] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:560:DP-MST C]
<7> [443.331529] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:561:DP-MST D]
<7> [443.331654] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:508:eDP-1]
<7> [443.331850] i915 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [i915]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [443.332003] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [443.332235] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_C
<7> [443.332378] i915 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [i915]] DSS clock gating disabled for pipe_mask=0x4 (CLKGATE_DIS_DSSDSC=0xaa000000)
<7> [443.332555] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [443.332722] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:387:pipe C]
<7> [443.332927] 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> [443.333101] i915 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [i915]] aud_ts_cdclk set to M=60, N=432
<7> [443.333357] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:505:pipe D]
<7> [443.333467] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:505:pipe D]
<7> [443.333491] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:508:eDP-1]
<7> [443.333538] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:508:eDP-1] Limiting target display pipe bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [443.333681] i915 0000:00:02.0: [drm:intel_dp_compute_config_limits [i915]] [ENCODER:507:DDI A/PHY A][CRTC:505:pipe D] DP link limits: pixel clock 513820 kHz DSC off max lanes 4 max rate 540000 max pipe_bpp 30 min link_bpp 18.0000 max link_bpp 30.0000
<7> [443.333848] i915 0000:00:02.0: [drm:intel_dp_compute_output_format [i915]] DP lane count 4 clock 540000 bpp input 30 compressed 0.0000 HDR no link rate required 1926825 available 2160000
<7> [443.334089] i915 0000:00:02.0: [drm:intel_psr_compute_config [i915]] PSR2 not supported in transcoder D
<7> [443.334279] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:505:pipe D] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [443.334469] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:507:DDI A/PHY A] [CRTC:505:pipe D]
<7> [443.334623] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.enable (expected no, found yes)
<7> [443.334767] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.active (expected no, found yes)
<7> [443.334905] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in cpu_transcoder (expected -1, found 3)
<7> [443.335040] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in lane_count (expected 0, found 4)
<7> [443.335172] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 7483046/8388608 link 498869/524288)
<7> [443.335303] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [443.335431] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [443.335560] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [443.335690] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [443.335831] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)
<7> [443.335964] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)
<7> [443.336094] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)
<7> [443.336224] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)
<7> [443.336364] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [443.336493] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1801)
<7> [443.336621] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)
<7> [443.336759] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)
<7> [443.336925] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1878)
<7> [443.337134] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1878)
<7> [443.337323] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [443.337500] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [443.337656] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)
<7> [443.337810] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)
<7> [443.337943] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)
<7> [443.338073] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)
<7> [443.338204] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [443.338337] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1801)
<7> [443.338470] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)
<7> [443.338601] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)
<7> [443.338741] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1878)
<7> [443.338876] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1878)
<7> [443.339005] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [443.339133] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [443.339263] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [443.339395] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in enhanced_framing (expected no, found yes)
<7> [443.339524] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [443.339653] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 513820)
<7> [443.339795] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 513820)
<7> [443.339926] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in port_clock (expected 0, found 540000)
<7> [443.340074] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in set_context_latency (expected 0, found 1)
<7> [443.340261] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:505:pipe D] fastset requirement not met, forcing full modeset
<7> [443.340480] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0xf), mbus joined? no->no
<7> [443.340653] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:505:pipe D] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x8
<7> [443.340834] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:389:plane 1D] ddb ( 0 - 0) -> ( 0 - 1953), size 0 -> 1953
<7> [443.340961] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:499:cursor D] ddb ( 0 - 0) -> (1953 - 2048), size 0 -> 95
<7> [443.341090] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:389:plane 1D] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [443.341214] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:389:plane 1D] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 16, 16, 19, 19, 28, 0, 0, 0, 4, 0
<7> [443.341338] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:389:plane 1D] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 92, 365, 365, 434, 434, 638, 0, 0, 197, 92, 197
<7> [443.341463] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:389:plane 1D] 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> [443.341599] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:505:pipe D] data rate 2055280 num active planes 1
<7> [443.341742] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 28913 required 2159 qgv_peak_bw: 51200
<7> [443.341878] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 1: max bw 38000 required 2159 qgv_peak_bw: 51200
<7> [443.342011] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 2: max bw 38000 required 2159 qgv_peak_bw: 51200
<7> [443.342144] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 3: max bw 38000 required 2159 qgv_peak_bw: 51200
<7> [443.342274] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] Matching peaks QGV bw: 51200 for required data rate: 2159
<7> [443.342417] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:505:pipe D] min cdclk: 0 kHz -> 256910 kHz
<7> [443.342560] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Can change cdclk via squashing
<7> [443.342689] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 307200 kHz, actual 307200 kHz
<7> [443.342834] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [443.342982] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:505:pipe D] allocated DPLL 0
<7> [443.343118] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:505:pipe D] reserving DPLL 0
<7> [443.343254] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:505:pipe D] enable: yes [modeset]
<7> [443.343477] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [443.343743] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: D, pipe bpp: 30, dithering: 0
<7> [443.343941] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [443.344118] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [443.344270] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [443.344402] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [443.344539] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dp m_n: lanes: 4; data_m: 7483046, data_n: 8388608, link_m: 498869, link_n: 524288, tu: 64
<7> [443.344672] 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> [443.344825] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] fec: disabled, enhanced framing: enabled
<7> [443.344956] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sdp split: disabled
<7> [443.345086] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] psr: enabled, selective update: disabled, panel replay: disabled, selective fetch: disabled
<7> [443.345215] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] minimum hblank: 0
<7> [443.345343] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [443.345472] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] DP SDP: VSC, revision 2, length 8
<7> [443.345603] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pixelformat: RGB
<7> [443.345747] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] colorimetry: sRGB
<7> [443.345879] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] bpc: 0
<7> [443.346008] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dynamic range: VESA range
<7> [443.346137] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] content type: Not defined
<7> [443.346265] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1
<7> [443.346395] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 1
<7> [443.346526] 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> [443.346661] 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> [443.346867] 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> [443.347060] 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> [443.347244] 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> [443.347418] 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> [443.347570] 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> [443.347704] 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> [443.347862] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 540000, pixel rate 513820, min cdclk 256910, min voltage level 0
<7> [443.347996] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 48, ips linetime: 0
<7> [443.348125] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [443.348257] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 2880x1800+0+0
<7> [443.348385] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [443.348514] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [443.348645] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [443.348786] 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> [443.348918] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] c10pll_hw_state: fracen: yes,
<7> [443.349048] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] quot: 40960, rem: 0, den: 1,
<7> [443.349178] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] multiplier: 140, tx_clk_div: 0.
<7> [443.349308] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] c10pll_rawhw_state:
<7> [443.349439] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] tx: 0x10, cmn: 0x21
<7> [443.349572] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[0] = 0xf4, pll[1] = 0x0, pll[2] = 0xf8, pll[3] = 0x0
<7> [443.349703] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [443.349896] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [443.350042] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[12] = 0xa0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x0
<7> [443.350229] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[16] = 0x84, pll[17] = 0x4f, pll[18] = 0xe5, pll[19] = 0x23
<7> [443.350420] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [443.350606] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [443.350810] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [443.350944] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [443.351081] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [443.351004] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [443.351216] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [443.351351] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [443.351482] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [443.351615] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [443.351569] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [443.351768] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [443.351905] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [443.352036] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [443.352167] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:389:plane 1D] fb: [FB:566] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [443.352136] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [443.352300] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [443.352431] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [443.352565] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:419:plane 2D] fb: [NOFB], visible: no
<7> [443.352701] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:449:plane 3D] fb: [NOFB], visible: no
<7> [443.352856] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:479:plane 4D] fb: [NOFB], visible: no
<7> [443.352987] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:489:plane 5D] fb: [NOFB], visible: no
<7> [443.353115] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:499:cursor D] fb: [NOFB], visible: no
<7> [443.355998] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [443.356301] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [443.356514] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [443.356676] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_D
<7> [443.356887] i915 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [i915]] DSS clock gating enabled for pipe_mask=0x8 (CLKGATE_DIS_DSSDSC=0x2a000000)
<7> [443.357072] 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> [443.357299] i915 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [i915]] aud_ts_cdclk set to M=60, N=768
<7> [443.357486] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:507:DDI A/PHY A]
<7> [443.357642] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:516:DDI B/PHY B]
<7> [443.357790] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:518:DP-MST A]
<7> [443.357922] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:519:DP-MST B]
<7> [443.358051] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:520:DP-MST C]
<7> [443.358182] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:521:DP-MST D]
<7> [443.358313] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:534:DDI TC1/PHY TC1]
<7> [443.358443] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:536:DP-MST A]
<7> [443.358575] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:537:DP-MST B]
<7> [443.358706] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:538:DP-MST C]
<7> [443.358860] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:539:DP-MST D]
<7> [443.358991] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:543:DDI TC2/PHY TC2]
<7> [443.359124] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:547:DDI TC3/PHY TC3]
<7> [443.359252] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:549:DP-MST A]
<7> [443.359381] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:550:DP-MST B]
<7> [443.359509] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:551:DP-MST C]
<7> [443.359639] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:552:DP-MST D]
<7> [443.359790] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:556:DDI TC4/PHY TC4]
<7> [443.359941] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:558:DP-MST A]
<7> [443.360126] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:559:DP-MST B]
<7> [443.360308] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:560:DP-MST C]
<7> [443.360493] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:561:DP-MST D]
<7> [443.360691] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [443.360936] i915 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [i915]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [443.361140] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling AUX_A
<7> [443.362550] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x8, on? 0) for [CRTC:505:pipe D]
<7> [443.362700] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [443.364703] i915 0000:00:02.0: [drm:intel_pps_on_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 turn panel power on
<7> [443.364874] i915 0000:00:02.0: [drm:wait_panel_power_cycle [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 wait for panel power cycle (460 ms remaining)
<7> [443.826544] i915 0000:00:02.0: [drm:wait_panel_status [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [443.870572] i915 0000:00:02.0: [drm:wait_panel_status [i915]] Wait complete
<7> [443.871133] i915 0000:00:02.0: [drm:intel_pps_on_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 wait for panel power on
<7> [443.871723] i915 0000:00:02.0: [drm:wait_panel_status [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [443.903192] i915 0000:00:02.0: [drm:icp_irq_handler [i915]] hotplug event received, stat 0x00010000, dig 0x0000008a, pins 0x00000010, long 0x00000010
<7> [443.903768] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] digital hpd on [ENCODER:507:DDI A/PHY A] - long
<7> [443.904278] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [443.904853] i915 0000:00:02.0: [drm:intel_dp_hpd_pulse [i915]] ignoring long hpd on eDP [ENCODER:507:DDI A/PHY A]
<7> [444.076876] i915 0000:00:02.0: [drm:wait_panel_status [i915]] Wait complete
<7> [444.077917] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 turning VDD on
<7> [444.078682] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [444.081771] 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> [444.083125] i915 0000:00:02.0: [drm:intel_dp_start_link_train [i915]] [CONNECTOR:508:eDP-1][ENCODER:507:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [444.083868] i915 0000:00:02.0: [drm:intel_dp_start_link_train [i915]] [CONNECTOR:508:eDP-1][ENCODER:507:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 07
<7> [444.084900] i915 0000:00:02.0: [drm:intel_dp_set_signal_levels [i915]] [CONNECTOR:508:eDP-1][ENCODER:507:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [444.085897] i915 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [i915]] [CONNECTOR:508:eDP-1][ENCODER:507:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [444.086822] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:508:eDP-1][ENCODER:507:DDI A/PHY A][DPRX] Clock recovery OK
<7> [444.086943] i915 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [i915]] [CONNECTOR:508:eDP-1][ENCODER:507:DDI A/PHY A][DPRX] Using DP training pattern TPS3
<7> [444.088229] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:508:eDP-1][ENCODER:507:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [444.088355] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:508:eDP-1][ENCODER:507:DDI A/PHY A][DPRX] Link Training passed at link rate = 540000, lane count = 4
<7> [444.088935] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe D
<7> [444.089223] i915 0000:00:02.0: [drm:intel_edp_backlight_on [i915]]
<7> [444.089361] i915 0000:00:02.0: [drm:intel_backlight_enable [i915]] pipe D
<7> [444.089541] i915 0000:00:02.0: [drm:intel_backlight_set_pwm_level [i915]] [CONNECTOR:508:eDP-1] set backlight PWM = 13892
<7> [444.100387] i915 0000:00:02.0: [drm:intel_psr_post_plane_update [i915]] Enabling PSR1
<7> [444.101564] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:508:eDP-1]
<7> [444.101768] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:505:pipe D]
<7> [446.602494] i915 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 turning VDD off
<7> [446.603107] i915 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x00000067
<6> [446.938930] [IGT] kms_flip: finished subtest D-eDP1, FAIL
<6> [446.943405] [IGT] kms_flip: finished subtest flip-vs-expired-vblank-interruptible, FAIL
<7> [446.945971] i915 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:566] from all active usage due to RMFB ioctl
<7> [446.946012] i915 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:389:plane 1D] because [FB:566] is removed
<7> [446.946043] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:389:plane 1D] ddb ( 0 - 1953) -> ( 0 - 0), size 1953 -> 0
<7> [446.946207] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:389:plane 1D] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [446.946342] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:389:plane 1D] lines 4, 16, 16, 19, 19, 28, 0, 0, 0, 4, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [446.946490] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:389:plane 1D] blocks 92, 365, 365, 434, 434, 638, 0, 0, 197, 92, 197 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [446.946621] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:389:plane 1D] 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> [446.946766] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:505:pipe D] data rate 0 num active planes 0
<7> [446.946906] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 28913 required 0 qgv_peak_bw: 51200
<7> [446.947038] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 1: max bw 38000 required 0 qgv_peak_bw: 51200
<7> [446.947170] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 51200
<7> [446.947303] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 51200
<7> [446.947437] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] Matching peaks QGV bw: 51200 for required data rate: 0
<7> [446.947627] i915 0000:00:02.0: [drm:intel_psr_disable_locked [i915]] Disabling PSR1
<7> [446.954646] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 turning VDD on
<7> [446.954887] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006f
<6> [446.966038] [IGT] kms_flip: exiting, ret=98
<7> [446.974731] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:508:eDP-1] Limiting target display pipe bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [446.974901] i915 0000:00:02.0: [drm:intel_dp_compute_config_limits [i915]] [ENCODER:507:DDI A/PHY A][CRTC:151:pipe A] DP link limits: pixel clock 513820 kHz DSC off max lanes 4 max rate 540000 max pipe_bpp 30 min link_bpp 18.0000 max link_bpp 30.0000
<7> [446.975037] i915 0000:00:02.0: [drm:intel_dp_compute_output_format [i915]] DP lane count 4 clock 540000 bpp input 30 compressed 0.0000 HDR no link rate required 1926825 available 2160000
<7> [446.975176] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [446.975332] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:507:DDI A/PHY A] [CRTC:151:pipe A]
<7> [446.975480] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [446.975625] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [446.975766] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [446.975906] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [446.976044] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 7483046/8388608 link 498869/524288)
<7> [446.976183] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [446.976323] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [446.976472] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [446.976620] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [446.976769] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)
<7> [446.976909] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)
<7> [446.977047] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)
<7> [446.977185] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)
<7> [446.977323] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [446.977469] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1801)
<7> [446.977613] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)
<7> [446.977751] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)
<7> [446.977891] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1878)
<7> [446.978028] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1878)
<7> [446.978165] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [446.978300] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [446.978435] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)
<7> [446.978715] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)
<7> [446.978850] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)
<7> [446.978986] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)
<7> [446.979123] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [446.979258] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1801)
<7> [446.979392] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)
<7> [446.979537] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)
<7> [446.979671] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1878)
<7> [446.979803] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1878)
<7> [446.979936] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [446.980074] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [446.980209] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [446.980344] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in enhanced_framing (expected no, found yes)
<7> [446.980489] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [446.980624] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 513820)
<7> [446.980761] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 513820)
<7> [446.980895] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 0, found 540000)
<7> [446.981031] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in set_context_latency (expected 0, found 1)
<7> [446.981164] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [446.981297] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.enable (expected yes, found no)
<7> [446.981430] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.active (expected yes, found no)
<7> [446.981595] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in cpu_transcoder (expected 3, found -1)
<7> [446.981732] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in lane_count (expected 4, found 0)
<7> [446.981867] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in dp_m_n (expected tu 64 data 7483046/8388608 link 498869/524288, found tu 0, data 0/0 link 0/0)
<7> [446.982003] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [446.982137] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [446.982270] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [446.982403] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [446.982606] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [446.982760] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [446.982897] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [446.983032] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [446.983165] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [446.983299] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1801, found 0)
<7> [446.983453] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [446.983592] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [446.983729] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1878, found 0)
<7> [446.983861] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1878, found 0)
<7> [446.983995] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [446.984127] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [446.984263] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [446.984397] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [446.984541] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [446.984674] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [446.984806] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [446.984938] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1801, found 0)
<7> [446.985075] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [446.985213] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [446.985349] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1878, found 0)
<7> [446.985495] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1878, found 0)
<7> [446.985630] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [446.985765] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [446.985902] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [446.986040] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in enhanced_framing (expected yes, found no)
<7> [446.986176] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [446.986318] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 513820, found 0)
<7> [446.986459] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 513820, found 0)
<7> [446.986594] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in port_clock (expected 540000, found 0)
<7> [446.986732] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in set_context_latency (expected 1, found 0)
<7> [446.986868] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:505:pipe D] fastset requirement not met, forcing full modeset
<7> [446.987004] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:505:pipe D] releasing DPLL 0
<7> [446.987180] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [446.987317] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:151:pipe A] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x8 -> 0x1
<7> [446.987458] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:505:pipe D] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x8 -> 0x1
<7> [446.987596] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 4001), size 0 -> 4001
<7> [446.987730] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:145:cursor A] ddb ( 0 - 0) -> (4001 - 4096), size 0 -> 95
<7> [446.987863] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [446.987995] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 2, 16, 16, 19, 19, 28, 0, 0, 0, 3, 0
<7> [446.988127] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 49, 385, 385, 457, 457, 673, 0, 0, 63, 73, 87
<7> [446.988257] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 55, 425, 425, 504, 504, 742, 0, 0, 64, 82, 88
<7> [446.988387] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:499:cursor D] ddb (1953 - 2048) -> ( 0 - 0), size 95 -> 0
<7> [446.988537] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:151:pipe A] data rate 2055280 num active planes 1
<7> [446.988677] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 28913 required 2159 qgv_peak_bw: 51200
<7> [446.988817] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 1: max bw 38000 required 2159 qgv_peak_bw: 51200
<7> [446.988955] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 2: max bw 38000 required 2159 qgv_peak_bw: 51200
<7> [446.989090] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 3: max bw 38000 required 2159 qgv_peak_bw: 51200
<7> [446.989225] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] Matching peaks QGV bw: 51200 for required data rate: 2159
<7> [446.989360] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:151:pipe A] min cdclk: 0 kHz -> 256910 kHz
<7> [446.989506] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:505:pipe D] min cdclk: 256910 kHz -> 0 kHz
<7> [446.989670] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:151:pipe A] allocated DPLL 0
<7> [446.989810] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:151:pipe A] reserving DPLL 0
<7> [446.989952] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:151:pipe A] enable: yes [modeset]
<7> [446.990089] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [446.990226] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 30, dithering: 0
<7> [446.990364] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [446.990512] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [446.990648] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [446.990783] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [446.990916] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dp m_n: lanes: 4; data_m: 7483046, data_n: 8388608, link_m: 498869, link_n: 524288, tu: 64
<7> [446.991050] 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> [446.991184] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] fec: disabled, enhanced framing: enabled
<7> [446.991317] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sdp split: disabled
<7> [446.991457] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled
<7> [446.991594] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] minimum hblank: 0
<7> [446.991728] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [446.991862] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] DP SDP: VSC, revision 4, length 14
<7> [446.991997] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pixelformat: RGB
<7> [446.992129] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] colorimetry: sRGB
<7> [446.992261] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] bpc: 0
<7> [446.992393] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dynamic range: VESA range
<7> [446.992722] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] content type: Not defined
<7> [446.992861] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1
<7> [446.992998] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 1
<7> [446.993133] 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> [446.993267] 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> [446.993403] 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> [446.994607] 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> [446.994747] 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> [446.994884] 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> [446.995020] 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> [446.995155] 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> [446.995289] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 540000, pixel rate 513820, min cdclk 256910, min voltage level 0
<7> [446.995425] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 48, ips linetime: 0
<7> [446.995575] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [446.995710] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 2880x1800+0+0
<7> [446.995844] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [446.995979] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [446.996113] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [446.996251] 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> [446.996391] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] c10pll_hw_state: fracen: yes,
<7> [446.996539] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] quot: 40960, rem: 0, den: 1,
<7> [446.996675] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] multiplier: 140, tx_clk_div: 0.
<7> [446.996808] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] c10pll_rawhw_state:
<7> [446.996944] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] tx: 0x10, cmn: 0x21
<7> [446.997080] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[0] = 0xf4, pll[1] = 0x0, pll[2] = 0xf8, pll[3] = 0x0
<7> [446.997215] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [446.997350] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [446.997494] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[12] = 0xa0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x0
<7> [446.997629] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[16] = 0x84, pll[17] = 0x4f, pll[18] = 0xe5, pll[19] = 0x23
<7> [446.997762] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [446.997896] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [446.998032] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [446.998166] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [446.998301] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [446.998434] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [446.998582] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [446.998715] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [446.998851] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [446.998985] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [446.999121] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [446.999255] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [446.999390] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [FB:509] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [446.999536] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [446.999668] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [446.999799] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [446.999929] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [447.000062] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [447.000196] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [447.000331] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [447.000479] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:505:pipe D] enable: no [modeset]
<7> [447.000615] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:389:plane 1D] fb: [NOFB], visible: no
<7> [447.000750] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:419:plane 2D] fb: [NOFB], visible: no
<7> [447.000885] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:449:plane 3D] fb: [NOFB], visible: no
<7> [447.001018] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:479:plane 4D] fb: [NOFB], visible: no
<7> [447.001151] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:489:plane 5D] fb: [NOFB], visible: no
<7> [447.001283] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:499:cursor D] fb: [NOFB], visible: no
<7> [447.001672] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_A
<7> [447.001857] i915 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [i915]] DSS clock gating enabled for pipe_mask=0x1 (CLKGATE_DIS_DSSDSC=0x28000000)
<7> [447.002137] i915 0000:00:02.0: [drm:intel_edp_backlight_off [i915]]
<7> [447.210544] i915 0000:00:02.0: [drm:intel_backlight_set_pwm_level [i915]] [CONNECTOR:508:eDP-1] set backlight PWM = 0
<7> [447.211287] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe D
<7> [447.221183] i915 0000:00:02.0: [drm:intel_pps_off_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 turn panel power off
<7> [447.221719] i915 0000:00:02.0: [drm:intel_pps_off_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [447.222247] i915 0000:00:02.0: [drm:wait_panel_status [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [447.272547] i915 0000:00:02.0: [drm:wait_panel_status [i915]] Wait complete
<7> [447.273046] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x8, on? 1) for [CRTC:505:pipe D]
<7> [447.274277] i915 0000:00:02.0: [drm:icp_irq_handler [i915]] hotplug event received, stat 0x00010000, dig 0x0000008a, pins 0x00000010, long 0x00000010
<7> [447.274843] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] digital hpd on [ENCODER:507:DDI A/PHY A] - long
<7> [447.274973] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [447.275311] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling AUX_A
<7> [447.275356] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [447.275672] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:507:DDI A/PHY A]
<7> [447.275990] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:516:DDI B/PHY B]
<7> [447.275940] i915 0000:00:02.0: [drm:intel_dp_hpd_pulse [i915]] ignoring long hpd on eDP [ENCODER:507:DDI A/PHY A]
<7> [447.276405] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:518:DP-MST A]
<7> [447.276575] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:519:DP-MST B]
<7> [447.276721] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:520:DP-MST C]
<7> [447.276885] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:521:DP-MST D]
<7> [447.277066] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:534:DDI TC1/PHY TC1]
<7> [447.277213] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:536:DP-MST A]
<7> [447.277344] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:537:DP-MST B]
<7> [447.277475] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:538:DP-MST C]
<7> [447.277638] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:539:DP-MST D]
<7> [447.277819] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:543:DDI TC2/PHY TC2]
<7> [447.277996] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:547:DDI TC3/PHY TC3]
<7> [447.278160] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:549:DP-MST A]
<7> [447.278292] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:550:DP-MST B]
<7> [447.278418] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:551:DP-MST C]
<7> [447.278556] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:552:DP-MST D]
<7> [447.278687] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:556:DDI TC4/PHY TC4]
<7> [447.278816] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:558:DP-MST A]
<7> [447.278944] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:559:DP-MST B]
<7> [447.279072] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:560:DP-MST C]
<7> [447.279198] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:561:DP-MST D]
<7> [447.279365] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0xf
<7> [447.279611] i915 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [i915]] Changing mbus joined: no -> yes (pipe: *)
<7> [447.279751] i915 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [i915]] Updating dbuf ratio to 4 (mbus joined: yes)
<7> [447.279949] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling AUX_A
<7> [447.281309] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:151:pipe A]
<7> [447.281479] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [447.283693] i915 0000:00:02.0: [drm:intel_pps_on_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 turn panel power on
<7> [447.283844] i915 0000:00:02.0: [drm:wait_panel_power_cycle [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 wait for panel power cycle (490 ms remaining)
<7> [447.778992] i915 0000:00:02.0: [drm:wait_panel_status [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [447.808815] i915 0000:00:02.0: [drm:wait_panel_status [i915]] Wait complete
<7> [447.809369] i915 0000:00:02.0: [drm:intel_pps_on_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 wait for panel power on
<7> [447.809967] i915 0000:00:02.0: [drm:wait_panel_status [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [447.841382] i915 0000:00:02.0: [drm:icp_irq_handler [i915]] hotplug event received, stat 0x00010000, dig 0x0000008a, pins 0x00000010, long 0x00000010
<7> [447.841945] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] digital hpd on [ENCODER:507:DDI A/PHY A] - long
<7> [447.842455] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [447.843036] i915 0000:00:02.0: [drm:intel_dp_hpd_pulse [i915]] ignoring long hpd on eDP [ENCODER:507:DDI A/PHY A]
<7> [448.010891] i915 0000:00:02.0: [drm:wait_panel_status [i915]] Wait complete
<7> [448.011885] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 turning VDD on
<7> [448.012669] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [448.016155] 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> [448.017608] i915 0000:00:02.0: [drm:intel_dp_start_link_train [i915]] [CONNECTOR:508:eDP-1][ENCODER:507:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [448.018264] i915 0000:00:02.0: [drm:intel_dp_start_link_train [i915]] [CONNECTOR:508:eDP-1][ENCODER:507:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 07
<7> [448.019332] i915 0000:00:02.0: [drm:intel_dp_set_signal_levels [i915]] [CONNECTOR:508:eDP-1][ENCODER:507:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [448.020364] i915 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [i915]] [CONNECTOR:508:eDP-1][ENCODER:507:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [448.021447] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:508:eDP-1][ENCODER:507:DDI A/PHY A][DPRX] Clock recovery OK
<7> [448.022232] i915 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [i915]] [CONNECTOR:508:eDP-1][ENCODER:507:DDI A/PHY A][DPRX] Using DP training pattern TPS3
<7> [448.023822] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:508:eDP-1][ENCODER:507:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [448.024016] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:508:eDP-1][ENCODER:507:DDI A/PHY A][DPRX] Link Training passed at link rate = 540000, lane count = 4
<7> [448.024723] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [448.025069] i915 0000:00:02.0: [drm:intel_edp_backlight_on [i915]]
<7> [448.025249] i915 0000:00:02.0: [drm:intel_backlight_enable [i915]] pipe A
<7> [448.025485] i915 0000:00:02.0: [drm:intel_backlight_set_pwm_level [i915]] [CONNECTOR:508:eDP-1] set backlight PWM = 13892
<7> [448.036253] i915 0000:00:02.0: [drm:intel_psr_post_plane_update [i915]] Enabling PSR2
<7> [448.038101] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:508:eDP-1]
<7> [448.038332] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:151:pipe A]
<7> [448.041078] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_D
<7> [448.041241] i915 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [i915]] DSS clock gating disabled for pipe_mask=0x8 (CLKGATE_DIS_DSSDSC=0xa8000000)
<7> [448.041437] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [448.041635] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:505:pipe D]
<7> [448.042045] i915 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0
Created at 2026-04-29 19:25:00