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

Result: Fail

i915_display_info22 igt_runner22 results22.json results22-xe-load.json guc_logs22.tar i915_display_info_post_exec22 serial_data22 boot22 dmesg22

DetailValue
Duration 6.90 seconds
Hostname 
shard-lnl-3
Igt-Version 
IGT-Version: 2.4-gf29c30f54 (x86_64) (Linux: 7.1.0-rc3-lgci-xe-xe-5061-3d32fac0aa7111d9e-debug+ x86_64)
Out 
Starting dynamic subtest: C-eDP1
  2880x1800: 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa 
Stack trace:
  #0 ../lib/igt_core.c:2074 __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 C-eDP1: FAIL (6.898s)
Subtest flip-vs-expired-vblank-interruptible: FAIL (29.383s)
Stack trace:
  #0 ../lib/igt_core.c:2074 __igt_fail_assert()
  #1 ../lib/igt_kms.c:2352 kmstest_set_connector_dpms()
  #2 ../lib/igt_core.c:2960 igt_atexit_handler()
  #3 [erand48_r+0x1d6]
  #4 [exit+0x1e]
  #5 ../lib/igt_core.c:2415 igt_exit()
  #6 [main+0x3f]
  #7 [__libc_init_first+0x8a]
  #8 [__libc_start_main+0x8b]
  #9 [_start+0x25]
Err 
Starting dynamic subtest: C-eDP1
[495.492719] (kms_flip:6789) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
[495.492766] (kms_flip:6789) CRITICAL: Failed assertion: end - start < 500
[495.492794] (kms_flip:6789) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest C-eDP1 failed.
**** DEBUG ****
[493.475440] (kms_flip:6789) DEBUG: Vblank took 96us
[493.491949] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.013245
last_received_ts = 495.012451
last_seq = 2180
current_ts = 495.029938
current_received_ts = 495.029083
current_seq = 2181
count = 221
seq_step = 1
[493.492050] (kms_flip:6789) DEBUG: Vblank took 100us
[493.508674] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.029938
last_received_ts = 495.029083
last_seq = 2181
current_ts = 495.046600
current_received_ts = 495.045807
current_seq = 2182
count = 222
seq_step = 1
[493.508771] (kms_flip:6789) DEBUG: Vblank took 95us
[493.525317] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.046600
last_received_ts = 495.045807
last_seq = 2182
current_ts = 495.063263
current_received_ts = 495.062439
current_seq = 2183
count = 223
seq_step = 1
[493.525426] (kms_flip:6789) DEBUG: Vblank took 108us
[493.541971] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.063263
last_received_ts = 495.062439
last_seq = 2183
current_ts = 495.079926
current_received_ts = 495.079102
current_seq = 2184
count = 224
seq_step = 1
[493.542070] (kms_flip:6789) DEBUG: Vblank took 98us
[493.558652] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.079926
last_received_ts = 495.079102
last_seq = 2184
current_ts = 495.096588
current_received_ts = 495.095764
current_seq = 2185
count = 225
seq_step = 1
[493.558756] (kms_flip:6789) DEBUG: Vblank took 102us
[493.575320] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.096588
last_received_ts = 495.095764
last_seq = 2185
current_ts = 495.113251
current_received_ts = 495.112457
current_seq = 2186
count = 226
seq_step = 1
[493.575441] (kms_flip:6789) DEBUG: Vblank took 120us
[493.591953] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.113251
last_received_ts = 495.112457
last_seq = 2186
current_ts = 495.129913
current_received_ts = 495.129089
current_seq = 2187
count = 227
seq_step = 1
[493.592062] (kms_flip:6789) DEBUG: Vblank took 109us
[493.608594] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.129913
last_received_ts = 495.129089
last_seq = 2187
current_ts = 495.146576
current_received_ts = 495.145721
current_seq = 2188
count = 228
seq_step = 1
[493.608634] (kms_flip:6789) DEBUG: Vblank took 39us
[493.625259] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.146576
last_received_ts = 495.145721
last_seq = 2188
current_ts = 495.163239
current_received_ts = 495.162384
current_seq = 2189
count = 229
seq_step = 1
[493.625291] (kms_flip:6789) DEBUG: Vblank took 31us
[493.641936] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.163239
last_received_ts = 495.162384
last_seq = 2189
current_ts = 495.179901
current_received_ts = 495.179077
current_seq = 2190
count = 230
seq_step = 1
[493.642038] (kms_flip:6789) DEBUG: Vblank took 101us
[493.658586] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.179901
last_received_ts = 495.179077
last_seq = 2190
current_ts = 495.196564
current_received_ts = 495.195709
current_seq = 2191
count = 231
seq_step = 1
[493.658619] (kms_flip:6789) DEBUG: Vblank took 32us
[493.675293] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.196564
last_received_ts = 495.195709
last_seq = 2191
current_ts = 495.213226
current_received_ts = 495.212433
current_seq = 2192
count = 232
seq_step = 1
[493.675391] (kms_flip:6789) DEBUG: Vblank took 98us
[493.691917] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.213226
last_received_ts = 495.212433
last_seq = 2192
current_ts = 495.229889
current_received_ts = 495.229065
current_seq = 2193
count = 233
seq_step = 1
[493.692015] (kms_flip:6789) DEBUG: Vblank took 96us
[493.708630] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.229889
last_received_ts = 495.229065
last_seq = 2193
current_ts = 495.246552
current_received_ts = 495.245758
current_seq = 2194
count = 234
seq_step = 1
[493.708730] (kms_flip:6789) DEBUG: Vblank took 99us
[493.725298] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.246552
last_received_ts = 495.245758
last_seq = 2194
current_ts = 495.263245
current_received_ts = 495.262421
current_seq = 2195
count = 235
seq_step = 1
[493.725392] (kms_flip:6789) DEBUG: Vblank took 93us
[493.741930] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.263245
last_received_ts = 495.262421
last_seq = 2195
current_ts = 495.279907
current_received_ts = 495.279053
current_seq = 2196
count = 236
seq_step = 1
[493.741958] (kms_flip:6789) DEBUG: Vblank took 27us
[493.758631] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.279907
last_received_ts = 495.279053
last_seq = 2196
current_ts = 495.296570
current_received_ts = 495.295776
current_seq = 2197
count = 237
seq_step = 1
[493.758732] (kms_flip:6789) DEBUG: Vblank took 100us
[493.775270] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.296570
last_received_ts = 495.295776
last_seq = 2197
current_ts = 495.313232
current_received_ts = 495.312408
current_seq = 2198
count = 238
seq_step = 1
[493.775376] (kms_flip:6789) DEBUG: Vblank took 105us
[493.791923] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.313232
last_received_ts = 495.312408
last_seq = 2198
current_ts = 495.329895
current_received_ts = 495.329071
current_seq = 2199
count = 239
seq_step = 1
[493.791953] (kms_flip:6789) DEBUG: Vblank took 28us
[493.808609] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.329895
last_received_ts = 495.329071
last_seq = 2199
current_ts = 495.346558
current_received_ts = 495.345734
current_seq = 2200
count = 240
seq_step = 1
[493.808707] (kms_flip:6789) DEBUG: Vblank took 97us
[493.825278] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.346558
last_received_ts = 495.345734
last_seq = 2200
current_ts = 495.363220
current_received_ts = 495.362427
current_seq = 2201
count = 241
seq_step = 1
[493.825380] (kms_flip:6789) DEBUG: Vblank took 100us
[493.841915] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.363220
last_received_ts = 495.362427
last_seq = 2201
current_ts = 495.379883
current_received_ts = 495.379059
current_seq = 2202
count = 242
seq_step = 1
[493.842023] (kms_flip:6789) DEBUG: Vblank took 107us
[493.858605] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.379883
last_received_ts = 495.379059
last_seq = 2202
current_ts = 495.396545
current_received_ts = 495.395752
current_seq = 2203
count = 243
seq_step = 1
[493.858710] (kms_flip:6789) DEBUG: Vblank took 104us
[493.875253] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.396545
last_received_ts = 495.395752
last_seq = 2203
current_ts = 495.413208
current_received_ts = 495.412384
current_seq = 2204
count = 244
seq_step = 1
[493.875355] (kms_flip:6789) DEBUG: Vblank took 102us
[493.891886] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.413208
last_received_ts = 495.412384
last_seq = 2204
current_ts = 495.429871
current_received_ts = 495.429016
current_seq = 2205
count = 245
seq_step = 1
[493.891916] (kms_flip:6789) DEBUG: Vblank took 29us
[493.908602] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.429871
last_received_ts = 495.429016
last_seq = 2205
current_ts = 495.446533
current_received_ts = 495.445740
current_seq = 2206
count = 246
seq_step = 1
[493.908716] (kms_flip:6789) DEBUG: Vblank took 113us
[493.925242] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.446533
last_received_ts = 495.445740
last_seq = 2206
current_ts = 495.463196
current_received_ts = 495.462372
current_seq = 2207
count = 247
seq_step = 1
[493.925347] (kms_flip:6789) DEBUG: Vblank took 104us
[493.941893] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.463196
last_received_ts = 495.462372
last_seq = 2207
current_ts = 495.479858
current_received_ts = 495.479034
current_seq = 2208
count = 248
seq_step = 1
[493.941924] (kms_flip:6789) DEBUG: Vblank took 30us
[493.958595] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.479858
last_received_ts = 495.479034
last_seq = 2208
current_ts = 495.496552
current_received_ts = 495.495728
current_seq = 2209
count = 249
seq_step = 1
[493.958700] (kms_flip:6789) DEBUG: Vblank took 104us
[493.975242] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.496552
last_received_ts = 495.495728
last_seq = 2209
current_ts = 495.513214
current_received_ts = 495.512390
current_seq = 2210
count = 250
seq_step = 1
[493.975350] (kms_flip:6789) DEBUG: Vblank took 107us
[493.991866] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.513214
last_received_ts = 495.512390
last_seq = 2210
current_ts = 495.529877
current_received_ts = 495.529022
current_seq = 2211
count = 251
seq_step = 1
[493.991894] (kms_flip:6789) DEBUG: Vblank took 27us
[494.008532] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.529877
last_received_ts = 495.529022
last_seq = 2211
current_ts = 495.546539
current_received_ts = 495.545685
current_seq = 2212
count = 252
seq_step = 1
[494.008563] (kms_flip:6789) DEBUG: Vblank took 31us
[494.025196] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.546539
last_received_ts = 495.545685
last_seq = 2212
current_ts = 495.563202
current_received_ts = 495.562347
current_seq = 2213
count = 253
seq_step = 1
[494.025228] (kms_flip:6789) DEBUG: Vblank took 32us
[494.041965] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.563202
last_received_ts = 495.562347
last_seq = 2213
current_ts = 495.579865
current_received_ts = 495.579071
current_seq = 2214
count = 254
seq_step = 1
[494.042071] (kms_flip:6789) DEBUG: Vblank took 91us
[494.058547] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.579865
last_received_ts = 495.579071
last_seq = 2214
current_ts = 495.596527
current_received_ts = 495.595703
current_seq = 2215
count = 255
seq_step = 1
[494.058586] (kms_flip:6789) DEBUG: Vblank took 34us
[494.075186] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.596527
last_received_ts = 495.595703
last_seq = 2215
current_ts = 495.613190
current_received_ts = 495.612335
current_seq = 2216
count = 256
seq_step = 1
[494.075223] (kms_flip:6789) DEBUG: Vblank took 34us
[494.092017] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.613190
last_received_ts = 495.612335
last_seq = 2216
current_ts = 495.629852
current_received_ts = 495.629150
current_seq = 2217
count = 257
seq_step = 1
[494.092068] (kms_flip:6789) DEBUG: Vblank took 42us
[494.108563] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.629852
last_received_ts = 495.629150
last_seq = 2217
current_ts = 495.646515
current_received_ts = 495.645721
current_seq = 2218
count = 258
seq_step = 1
[494.108595] (kms_flip:6789) DEBUG: Vblank took 31us
[494.125214] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.646515
last_received_ts = 495.645721
last_seq = 2218
current_ts = 495.663177
current_received_ts = 495.662354
current_seq = 2219
count = 259
seq_step = 1
[494.125322] (kms_flip:6789) DEBUG: Vblank took 107us
[494.141877] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.663177
last_received_ts = 495.662354
last_seq = 2219
current_ts = 495.679840
current_received_ts = 495.679016
current_seq = 2220
count = 260
seq_step = 1
[494.141906] (kms_flip:6789) DEBUG: Vblank took 28us
[494.158552] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.679840
last_received_ts = 495.679016
last_seq = 2220
current_ts = 495.696503
current_received_ts = 495.695709
current_seq = 2221
count = 261
seq_step = 1
[494.158651] (kms_flip:6789) DEBUG: Vblank took 98us
[494.175202] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.696503
last_received_ts = 495.695709
last_seq = 2221
current_ts = 495.713196
current_received_ts = 495.712372
current_seq = 2222
count = 262
seq_step = 1
[494.175315] (kms_flip:6789) DEBUG: Vblank took 112us
[494.191875] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.713196
last_received_ts = 495.712372
last_seq = 2222
current_ts = 495.729858
current_received_ts = 495.729034
current_seq = 2223
count = 263
seq_step = 1
[494.191973] (kms_flip:6789) DEBUG: Vblank took 97us
[494.208556] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.729858
last_received_ts = 495.729034
last_seq = 2223
current_ts = 495.746521
current_received_ts = 495.745728
current_seq = 2224
count = 264
seq_step = 1
[494.208663] (kms_flip:6789) DEBUG: Vblank took 106us
[494.225200] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.746521
last_received_ts = 495.745728
last_seq = 2224
current_ts = 495.763184
current_received_ts = 495.762360
current_seq = 2225
count = 265
seq_step = 1
[494.225298] (kms_flip:6789) DEBUG: Vblank took 96us
[494.241893] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.763184
last_received_ts = 495.762360
last_seq = 2225
current_ts = 495.779846
current_received_ts = 495.779053
current_seq = 2226
count = 266
seq_step = 1
[494.241994] (kms_flip:6789) DEBUG: Vblank took 100us
[494.258543] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.779846
last_received_ts = 495.779053
last_seq = 2226
current_ts = 495.796509
current_received_ts = 495.795715
current_seq = 2227
count = 267
seq_step = 1
[494.258645] (kms_flip:6789) DEBUG: Vblank took 101us
[494.275196] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.796509
last_received_ts = 495.795715
last_seq = 2227
current_ts = 495.813171
current_received_ts = 495.812347
current_seq = 2228
count = 268
seq_step = 1
[494.275296] (kms_flip:6789) DEBUG: Vblank took 99us
[494.291861] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.813171
last_received_ts = 495.812347
last_seq = 2228
current_ts = 495.829834
current_received_ts = 495.829010
current_seq = 2229
count = 269
seq_step = 1
[494.291966] (kms_flip:6789) DEBUG: Vblank took 104us
[494.308546] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.829834
last_received_ts = 495.829010
last_seq = 2229
current_ts = 495.846497
current_received_ts = 495.845703
current_seq = 2230
count = 270
seq_step = 1
[494.308649] (kms_flip:6789) DEBUG: Vblank took 103us
[494.325151] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.846497
last_received_ts = 495.845703
last_seq = 2230
current_ts = 495.863159
current_received_ts = 495.862305
current_seq = 2231
count = 271
seq_step = 1
[494.325181] (kms_flip:6789) DEBUG: Vblank took 31us
[494.341821] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.863159
last_received_ts = 495.862305
last_seq = 2231
current_ts = 495.879822
current_received_ts = 495.878998
current_seq = 2232
count = 272
seq_step = 1
[494.341856] (kms_flip:6789) DEBUG: Vblank took 34us
[494.358498] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.879822
last_received_ts = 495.878998
last_seq = 2232
current_ts = 495.896484
current_received_ts = 495.895660
current_seq = 2233
count = 273
seq_step = 1
[494.358526] (kms_flip:6789) DEBUG: Vblank took 28us
[494.375145] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.896484
last_received_ts = 495.895660
last_seq = 2233
current_ts = 495.913147
current_received_ts = 495.912323
current_seq = 2234
count = 274
seq_step = 1
[494.375182] (kms_flip:6789) DEBUG: Vblank took 37us
[494.391862] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.913147
last_received_ts = 495.912323
last_seq = 2234
current_ts = 495.929810
current_received_ts = 495.929016
current_seq = 2235
count = 275
seq_step = 1
[494.391974] (kms_flip:6789) DEBUG: Vblank took 111us
[494.408516] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.929810
last_received_ts = 495.929016
last_seq = 2235
current_ts = 495.946472
current_received_ts = 495.945679
current_seq = 2236
count = 276
seq_step = 1
[494.408616] (kms_flip:6789) DEBUG: Vblank took 99us
[494.425132] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.946472
last_received_ts = 495.945679
last_seq = 2236
current_ts = 495.963165
current_received_ts = 495.962311
current_seq = 2237
count = 277
seq_step = 1
[494.425254] (kms_flip:6789) DEBUG: Vblank took 119us
[494.441853] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.963165
last_received_ts = 495.962311
last_seq = 2237
current_ts = 495.979828
current_received_ts = 495.979034
current_seq = 2238
count = 278
seq_step = 1
[494.441956] (kms_flip:6789) DEBUG: Vblank took 102us
[494.458507] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.979828
last_received_ts = 495.979034
last_seq = 2238
current_ts = 495.996490
current_received_ts = 495.995667
current_seq = 2239
count = 279
seq_step = 1
[494.458605] (kms_flip:6789) DEBUG: Vblank took 98us
[494.475134] (kms_flip:6789) DEBUG: name = flip
last_ts = 495.996490
last_received_ts = 495.995667
last_seq = 2239
current_ts = 496.013153
current_received_ts = 496.012299
current_seq = 2240
count = 280
seq_step = 1
[494.475258] (kms_flip:6789) DEBUG: Vblank took 118us
[494.491854] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.013153
last_received_ts = 496.012299
last_seq = 2240
current_ts = 496.029816
current_received_ts = 496.029022
current_seq = 2241
count = 281
seq_step = 1
[494.491956] (kms_flip:6789) DEBUG: Vblank took 101us
[494.508503] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.029816
last_received_ts = 496.029022
last_seq = 2241
current_ts = 496.046478
current_received_ts = 496.045685
current_seq = 2242
count = 282
seq_step = 1
[494.508612] (kms_flip:6789) DEBUG: Vblank took 107us
[494.525118] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.046478
last_received_ts = 496.045685
last_seq = 2242
current_ts = 496.063141
current_received_ts = 496.062286
current_seq = 2243
count = 283
seq_step = 1
[494.525235] (kms_flip:6789) DEBUG: Vblank took 117us
[494.541840] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.063141
last_received_ts = 496.062286
last_seq = 2243
current_ts = 496.079803
current_received_ts = 496.079010
current_seq = 2244
count = 284
seq_step = 1
[494.541944] (kms_flip:6789) DEBUG: Vblank took 104us
[494.558502] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.079803
last_received_ts = 496.079010
last_seq = 2244
current_ts = 496.096466
current_received_ts = 496.095673
current_seq = 2245
count = 285
seq_step = 1
[494.558603] (kms_flip:6789) DEBUG: Vblank took 100us
[494.575110] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.096466
last_received_ts = 496.095673
last_seq = 2245
current_ts = 496.113129
current_received_ts = 496.112274
current_seq = 2246
count = 286
seq_step = 1
[494.575210] (kms_flip:6789) DEBUG: Vblank took 100us
[494.591835] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.113129
last_received_ts = 496.112274
last_seq = 2246
current_ts = 496.129791
current_received_ts = 496.128998
current_seq = 2247
count = 287
seq_step = 1
[494.591940] (kms_flip:6789) DEBUG: Vblank took 105us
[494.608488] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.129791
last_received_ts = 496.128998
last_seq = 2247
current_ts = 496.146454
current_received_ts = 496.145660
current_seq = 2248
count = 288
seq_step = 1
[494.608601] (kms_flip:6789) DEBUG: Vblank took 112us
[494.625102] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.146454
last_received_ts = 496.145660
last_seq = 2248
current_ts = 496.163116
current_received_ts = 496.162292
current_seq = 2249
count = 289
seq_step = 1
[494.625202] (kms_flip:6789) DEBUG: Vblank took 99us
[494.641825] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.163116
last_received_ts = 496.162292
last_seq = 2249
current_ts = 496.179779
current_received_ts = 496.178986
current_seq = 2250
count = 290
seq_step = 1
[494.641932] (kms_flip:6789) DEBUG: Vblank took 105us
[494.658488] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.179779
last_received_ts = 496.178986
last_seq = 2250
current_ts = 496.196472
current_received_ts = 496.195679
current_seq = 2251
count = 291
seq_step = 1
[494.658590] (kms_flip:6789) DEBUG: Vblank took 101us
[494.675098] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.196472
last_received_ts = 496.195679
last_seq = 2251
current_ts = 496.213135
current_received_ts = 496.212280
current_seq = 2252
count = 292
seq_step = 1
[494.675208] (kms_flip:6789) DEBUG: Vblank took 109us
[494.691757] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.213135
last_received_ts = 496.212280
last_seq = 2252
current_ts = 496.229797
current_received_ts = 496.228943
current_seq = 2253
count = 293
seq_step = 1
[494.691788] (kms_flip:6789) DEBUG: Vblank took 30us
[494.708421] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.229797
last_received_ts = 496.228943
last_seq = 2253
current_ts = 496.246460
current_received_ts = 496.245605
current_seq = 2254
count = 294
seq_step = 1
[494.708451] (kms_flip:6789) DEBUG: Vblank took 29us
[494.725083] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.246460
last_received_ts = 496.245605
last_seq = 2254
current_ts = 496.263123
current_received_ts = 496.262268
current_seq = 2255
count = 295
seq_step = 1
[494.725114] (kms_flip:6789) DEBUG: Vblank took 30us
[494.741786] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.263123
last_received_ts = 496.262268
last_seq = 2255
current_ts = 496.279785
current_received_ts = 496.278961
current_seq = 2256
count = 296
seq_step = 1
[494.741890] (kms_flip:6789) DEBUG: Vblank took 103us
[494.758474] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.279785
last_received_ts = 496.278961
last_seq = 2256
current_ts = 496.296448
current_received_ts = 496.295654
current_seq = 2257
count = 297
seq_step = 1
[494.758568] (kms_flip:6789) DEBUG: Vblank took 93us
[494.775080] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.296448
last_received_ts = 496.295654
last_seq = 2257
current_ts = 496.313110
current_received_ts = 496.312256
current_seq = 2258
count = 298
seq_step = 1
[494.775110] (kms_flip:6789) DEBUG: Vblank took 29us
[494.791795] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.313110
last_received_ts = 496.312256
last_seq = 2258
current_ts = 496.329773
current_received_ts = 496.328979
current_seq = 2259
count = 299
seq_step = 1
[494.791906] (kms_flip:6789) DEBUG: Vblank took 109us
[494.808445] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.329773
last_received_ts = 496.328979
last_seq = 2259
current_ts = 496.346436
current_received_ts = 496.345642
current_seq = 2260
count = 300
seq_step = 1
[494.808554] (kms_flip:6789) DEBUG: Vblank took 108us
[494.825072] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.346436
last_received_ts = 496.345642
last_seq = 2260
current_ts = 496.363098
current_received_ts = 496.362244
current_seq = 2261
count = 301
seq_step = 1
[494.825182] (kms_flip:6789) DEBUG: Vblank took 110us
[494.841766] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.363098
last_received_ts = 496.362244
last_seq = 2261
current_ts = 496.379761
current_received_ts = 496.378967
current_seq = 2262
count = 302
seq_step = 1
[494.841794] (kms_flip:6789) DEBUG: Vblank took 27us
[494.858419] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.379761
last_received_ts = 496.378967
last_seq = 2262
current_ts = 496.396454
current_received_ts = 496.395599
current_seq = 2263
count = 303
seq_step = 1
[494.858517] (kms_flip:6789) DEBUG: Vblank took 97us
[494.875061] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.396454
last_received_ts = 496.395599
last_seq = 2263
current_ts = 496.413116
current_received_ts = 496.412262
current_seq = 2264
count = 304
seq_step = 1
[494.875180] (kms_flip:6789) DEBUG: Vblank took 118us
[494.891777] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.413116
last_received_ts = 496.412262
last_seq = 2264
current_ts = 496.429779
current_received_ts = 496.428955
current_seq = 2265
count = 305
seq_step = 1
[494.891884] (kms_flip:6789) DEBUG: Vblank took 106us
[494.908425] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.429779
last_received_ts = 496.428955
last_seq = 2265
current_ts = 496.446442
current_received_ts = 496.445618
current_seq = 2266
count = 306
seq_step = 1
[494.908546] (kms_flip:6789) DEBUG: Vblank took 120us
[494.925054] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.446442
last_received_ts = 496.445618
last_seq = 2266
current_ts = 496.463104
current_received_ts = 496.462250
current_seq = 2267
count = 307
seq_step = 1
[494.925151] (kms_flip:6789) DEBUG: Vblank took 96us
[494.941767] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.463104
last_received_ts = 496.462250
last_seq = 2267
current_ts = 496.479767
current_received_ts = 496.478973
current_seq = 2268
count = 308
seq_step = 1
[494.941879] (kms_flip:6789) DEBUG: Vblank took 111us
[494.958425] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.479767
last_received_ts = 496.478973
last_seq = 2268
current_ts = 496.496429
current_received_ts = 496.495605
current_seq = 2269
count = 309
seq_step = 1
[494.958525] (kms_flip:6789) DEBUG: Vblank took 97us
[494.975044] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.496429
last_received_ts = 496.495605
last_seq = 2269
current_ts = 496.513092
current_received_ts = 496.512238
current_seq = 2270
count = 310
seq_step = 1
[494.975144] (kms_flip:6789) DEBUG: Vblank took 100us
[494.991762] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.513092
last_received_ts = 496.512238
last_seq = 2270
current_ts = 496.529755
current_received_ts = 496.528961
current_seq = 2271
count = 311
seq_step = 1
[494.991868] (kms_flip:6789) DEBUG: Vblank took 105us
[495.008429] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.529755
last_received_ts = 496.528961
last_seq = 2271
current_ts = 496.546417
current_received_ts = 496.545624
current_seq = 2272
count = 312
seq_step = 1
[495.008533] (kms_flip:6789) DEBUG: Vblank took 102us
[495.025036] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.546417
last_received_ts = 496.545624
last_seq = 2272
current_ts = 496.563080
current_received_ts = 496.562225
current_seq = 2273
count = 313
seq_step = 1
[495.025148] (kms_flip:6789) DEBUG: Vblank took 111us
[495.041700] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.563080
last_received_ts = 496.562225
last_seq = 2273
current_ts = 496.579742
current_received_ts = 496.578888
current_seq = 2274
count = 314
seq_step = 1
[495.041732] (kms_flip:6789) DEBUG: Vblank took 32us
[495.058362] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.579742
last_received_ts = 496.578888
last_seq = 2274
current_ts = 496.596405
current_received_ts = 496.595551
current_seq = 2275
count = 315
seq_step = 1
[495.058395] (kms_flip:6789) DEBUG: Vblank took 33us
[495.075028] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.596405
last_received_ts = 496.595551
last_seq = 2275
current_ts = 496.613068
current_received_ts = 496.612213
current_seq = 2276
count = 316
seq_step = 1
[495.075126] (kms_flip:6789) DEBUG: Vblank took 97us
[495.091744] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.613068
last_received_ts = 496.612213
last_seq = 2276
current_ts = 496.629730
current_received_ts = 496.628937
current_seq = 2277
count = 317
seq_step = 1
[495.091860] (kms_flip:6789) DEBUG: Vblank took 114us
[495.108409] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.629730
last_received_ts = 496.628937
last_seq = 2277
current_ts = 496.646393
current_received_ts = 496.645599
current_seq = 2278
count = 318
seq_step = 1
[495.108525] (kms_flip:6789) DEBUG: Vblank took 114us
[495.125025] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.646393
last_received_ts = 496.645599
last_seq = 2278
current_ts = 496.663086
current_received_ts = 496.662231
current_seq = 2279
count = 319
seq_step = 1
[495.125054] (kms_flip:6789) DEBUG: Vblank took 28us
[495.141736] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.663086
last_received_ts = 496.662231
last_seq = 2279
current_ts = 496.679749
current_received_ts = 496.678925
current_seq = 2280
count = 320
seq_step = 1
[495.141845] (kms_flip:6789) DEBUG: Vblank took 108us
[495.158409] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.679749
last_received_ts = 496.678925
last_seq = 2280
current_ts = 496.696411
current_received_ts = 496.695618
current_seq = 2281
count = 321
seq_step = 1
[495.158505] (kms_flip:6789) DEBUG: Vblank took 96us
[495.175019] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.696411
last_received_ts = 496.695618
last_seq = 2281
current_ts = 496.713074
current_received_ts = 496.712219
current_seq = 2282
count = 322
seq_step = 1
[495.175051] (kms_flip:6789) DEBUG: Vblank took 30us
[495.191735] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.713074
last_received_ts = 496.712219
last_seq = 2282
current_ts = 496.729736
current_received_ts = 496.728943
current_seq = 2283
count = 323
seq_step = 1
[495.191847] (kms_flip:6789) DEBUG: Vblank took 109us
[495.208401] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.729736
last_received_ts = 496.728943
last_seq = 2283
current_ts = 496.746399
current_received_ts = 496.745605
current_seq = 2284
count = 324
seq_step = 1
[495.208503] (kms_flip:6789) DEBUG: Vblank took 101us
[495.225007] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.746399
last_received_ts = 496.745605
last_seq = 2284
current_ts = 496.763062
current_received_ts = 496.762207
current_seq = 2285
count = 325
seq_step = 1
[495.225111] (kms_flip:6789) DEBUG: Vblank took 104us
[495.241721] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.763062
last_received_ts = 496.762207
last_seq = 2285
current_ts = 496.779724
current_received_ts = 496.778931
current_seq = 2286
count = 326
seq_step = 1
[495.241843] (kms_flip:6789) DEBUG: Vblank took 121us
[495.258377] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.779724
last_received_ts = 496.778931
last_seq = 2286
current_ts = 496.796387
current_received_ts = 496.795593
current_seq = 2287
count = 327
seq_step = 1
[495.258489] (kms_flip:6789) DEBUG: Vblank took 111us
[495.274997] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.796387
last_received_ts = 496.795593
last_seq = 2287
current_ts = 496.813049
current_received_ts = 496.812195
current_seq = 2288
count = 328
seq_step = 1
[495.275095] (kms_flip:6789) DEBUG: Vblank took 98us
[495.291710] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.813049
last_received_ts = 496.812195
last_seq = 2288
current_ts = 496.829712
current_received_ts = 496.828918
current_seq = 2289
count = 329
seq_step = 1
[495.291819] (kms_flip:6789) DEBUG: Vblank took 108us
[495.308386] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.829712
last_received_ts = 496.828918
last_seq = 2289
current_ts = 496.846375
current_received_ts = 496.845581
current_seq = 2290
count = 330
seq_step = 1
[495.308486] (kms_flip:6789) DEBUG: Vblank took 100us
[495.324989] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.846375
last_received_ts = 496.845581
last_seq = 2290
current_ts = 496.863068
current_received_ts = 496.862213
current_seq = 2291
count = 331
seq_step = 1
[495.325120] (kms_flip:6789) DEBUG: Vblank took 130us
[495.341712] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.863068
last_received_ts = 496.862213
last_seq = 2291
current_ts = 496.879700
current_received_ts = 496.878906
current_seq = 2292
count = 332
seq_step = 1
[495.341819] (kms_flip:6789) DEBUG: Vblank took 104us
[495.358376] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.879700
last_received_ts = 496.878906
last_seq = 2292
current_ts = 496.896393
current_received_ts = 496.895569
current_seq = 2293
count = 333
seq_step = 1
[495.358482] (kms_flip:6789) DEBUG: Vblank took 104us
[495.374980] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.896393
last_received_ts = 496.895569
last_seq = 2293
current_ts = 496.913055
current_received_ts = 496.912201
current_seq = 2294
count = 334
seq_step = 1
[495.375078] (kms_flip:6789) DEBUG: Vblank took 98us
[495.391644] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.913055
last_received_ts = 496.912201
last_seq = 2294
current_ts = 496.929718
current_received_ts = 496.928864
current_seq = 2295
count = 335
seq_step = 1
[495.391677] (kms_flip:6789) DEBUG: Vblank took 30us
[495.408309] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.929718
last_received_ts = 496.928864
last_seq = 2295
current_ts = 496.946381
current_received_ts = 496.945526
current_seq = 2296
count = 336
seq_step = 1
[495.408341] (kms_flip:6789) DEBUG: Vblank took 30us
[495.424973] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.946381
last_received_ts = 496.945526
last_seq = 2296
current_ts = 496.963043
current_received_ts = 496.962189
current_seq = 2297
count = 337
seq_step = 1
[495.425077] (kms_flip:6789) DEBUG: Vblank took 104us
[495.441660] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.963043
last_received_ts = 496.962189
last_seq = 2297
current_ts = 496.979706
current_received_ts = 496.978882
current_seq = 2298
count = 338
seq_step = 1
[495.441694] (kms_flip:6789) DEBUG: Vblank took 33us
[495.458351] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.979706
last_received_ts = 496.978882
last_seq = 2298
current_ts = 496.996368
current_received_ts = 496.995575
current_seq = 2299
count = 339
seq_step = 1
[495.458457] (kms_flip:6789) DEBUG: Vblank took 105us
[495.474966] (kms_flip:6789) DEBUG: name = flip
last_ts = 496.996368
last_received_ts = 496.995575
last_seq = 2299
current_ts = 497.013031
current_received_ts = 497.012177
current_seq = 2300
count = 340
seq_step = 1
[495.475074] (kms_flip:6789) DEBUG: Vblank took 106us
[495.491684] (kms_flip:6789) DEBUG: name = flip
last_ts = 497.013031
last_received_ts = 497.012177
last_seq = 2300
current_ts = 497.029694
current_received_ts = 497.028900
current_seq = 2301
count = 341
seq_step = 1
[495.492716] (kms_flip:6789) DEBUG: Vblank took 1032us
[495.492719] (kms_flip:6789) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
[495.492766] (kms_flip:6789) CRITICAL: Failed assertion: end - start < 500
[495.492794] (kms_flip:6789) CRITICAL: Last errno: 4, Interrupted system call
[495.494430] (kms_flip:6789) igt_core-INFO: Stack trace:
[495.499075] (kms_flip:6789) igt_core-INFO:   #0 ../lib/igt_core.c:2074 __igt_fail_assert()
[495.499534] (kms_flip:6789) igt_core-INFO:   #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
[495.499564] (kms_flip:6789) igt_core-INFO:   #2 ../tests/kms_flip.c:1920 run_test()
[495.499577] (kms_flip:6789) igt_core-INFO:   #3 ../tests/kms_flip.c:2220 __igt_unique____real_main2105()
[495.499591] (kms_flip:6789) igt_core-INFO:   #4 ../tests/kms_flip.c:2105 main()
[495.501382] (kms_flip:6789) igt_core-INFO:   #5 [__libc_init_first+0x8a]
[495.501949] (kms_flip:6789) igt_core-INFO:   #6 [__libc_start_main+0x8b]
[495.502092] (kms_flip:6789) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest C-eDP1: FAIL (6.898s)
Subtest flip-vs-expired-vblank-interruptible: FAIL (29.383s)
[496.681882] (kms_flip:6789) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2352:
[496.681910] (kms_flip:6789) igt_kms-CRITICAL: Failed assertion: found_it
[496.681919] (kms_flip:6789) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor
[496.681925] (kms_flip:6789) igt_kms-CRITICAL: DPMS property not found on 393
Dmesg
<6> [490.355435] [IGT] kms_flip: starting dynamic subtest C-eDP1
<7> [490.356471] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:429]
<7> [490.356803] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:431]
<7> [490.372198] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [490.372422] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [490.372543] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [490.412457] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [490.412579] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [490.412747] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [490.412976] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:270:pipe B]
<7> [490.413042] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [490.413167] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [490.413293] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [490.413417] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [490.413522] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in dp_m_n (expected tu 64 data 6329869/8388608 link 421991/524288, found tu 0, data 0/0 link 0/0)
<7> [490.413618] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [490.413716] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [490.413817] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [490.413913] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [490.414008] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [490.414103] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [490.414304] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [490.414431] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [490.414548] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [490.414638] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1800, found 0)
<7> [490.414719] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [490.414800] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [490.414881] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1906, found 0)
<7> [490.414962] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1906, found 0)
<7> [490.415043] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [490.415122] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [490.415228] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [490.415332] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [490.415436] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [490.415526] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [490.415612] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [490.415698] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1800, found 0)
<7> [490.415784] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [490.415861] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [490.415938] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1906, found 0)
<7> [490.416015] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1906, found 0)
<7> [490.416094] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [490.416185] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [490.416290] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [490.416394] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [490.416491] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 347710, found 0)
<7> [490.416573] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 347710, found 0)
<7> [490.416651] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:270:pipe B] fastset requirement not met in port_clock (expected 432000, found 0)
<7> [490.416730] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:270:pipe B] fastset requirement not met, forcing full modeset
<7> [490.416809] xe 0000:00:02.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:270:pipe B] releasing DPLL 0
<7> [490.416916] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [490.416985] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:270:pipe B] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [490.417078] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:264:cursor B] ddb (4051 - 4096) -> ( 0 - 0), size 45 -> 0
<7> [490.417150] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:270:pipe B] min cdclk: 173855 kHz -> 0 kHz
<7> [490.417260] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [490.417370] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 153600 kHz, actual 153600 kHz
<7> [490.417477] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [490.417593] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:270:pipe B] enable: no [modeset]
<7> [490.417686] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:154:plane 1B] fb: [NOFB], visible: no
<7> [490.417777] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:184:plane 2B] fb: [NOFB], visible: no
<7> [490.417868] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:214:plane 3B] fb: [NOFB], visible: no
<7> [490.417950] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:244:plane 4B] fb: [NOFB], visible: no
<7> [490.418033] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:254:plane 5B] fb: [NOFB], visible: no
<7> [490.418117] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:264:cursor B] fb: [NOFB], visible: no
<7> [490.418345] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [490.418474] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [490.624177] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:393:eDP-1] set backlight PWM = 0
<7> [490.624530] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe B
<7> [490.639458] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turn panel power off
<7> [490.639589] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [490.639720] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000003 PP_CONTROL: 0x00000060
<7> [490.639970] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [490.640204] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - short
<7> [490.692262] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [490.692421] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - short
<7> [490.700277] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [490.700453] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disable DPLL 0 (active 0x2, on? 1) for [CRTC:270:pipe B]
<7> [490.700492] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [490.700627] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [490.701589] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disabling DPLL 0
<7> [490.701778] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [490.701938] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:392:DDI A/PHY A]
<7> [490.702021] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DDI TC1/PHY TC1]
<7> [490.702095] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST A]
<7> [490.702189] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:404:DP-MST B]
<7> [490.702282] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:405:DP-MST C]
<7> [490.702373] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DDI TC2/PHY TC2]
<7> [490.702460] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST A]
<7> [490.702529] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:416:DP-MST B]
<7> [490.702598] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:417:DP-MST C]
<7> [490.702670] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DDI TC3/PHY TC3]
<7> [490.702741] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST A]
<7> [490.702810] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:424:DP-MST B]
<7> [490.702880] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:425:DP-MST C]
<7> [490.702949] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:393:eDP-1]
<7> [490.703060] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [490.703141] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [490.703221] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [490.703412] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_B
<7> [490.703511] xe 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x2 (CLKGATE_DIS_DSSDSC=0xaa000000)
<7> [490.703623] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [490.703733] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:270:pipe B]
<7> [490.703809] xe 0000:00:02.0: [drm:intel_set_cdclk [xe]] Post changing CDCLK to 153600 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [490.703910] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [490.703999] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=384
<7> [490.704251] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:389:pipe C]
<7> [490.704384] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:389:pipe C]
<7> [490.704407] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:393:eDP-1]
<7> [490.704468] xe 0000:00:02.0: [drm:compute_baseline_pipe_bpp [xe]] [CONNECTOR:393:eDP-1] Limiting target display pipe bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [490.704574] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:392:DDI A/PHY A][CRTC:389:pipe C] DP link limits: pixel clock 347710 kHz DSC off max lanes 4 max rate 810000 max pipe_bpp 30 min link_bpp 18.0000 max link_bpp 30.0000
<7> [490.704661] xe 0000:00:02.0: [drm:intel_dp_compute_output_format [xe]] DP lane count 4 clock 432000 bpp input 30 compressed 0.0000 HDR no link rate required 1303913 available 1728000
<7> [490.704741] xe 0000:00:02.0: [drm:intel_psr_compute_config [xe]] PSR2 not supported in transcoder C
<7> [490.704809] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:389:pipe C] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [490.704893] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:392:DDI A/PHY A] [CRTC:389:pipe C]
<7> [490.704980] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.enable (expected no, found yes)
<7> [490.705069] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.active (expected no, found yes)
<7> [490.705172] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2)
<7> [490.705283] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in lane_count (expected 0, found 4)
<7> [490.705392] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6329869/8388608 link 421991/524288)
<7> [490.705499] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [490.705588] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [490.705676] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [490.705763] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [490.705849] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)
<7> [490.705928] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)
<7> [490.706006] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)
<7> [490.706094] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)
<7> [490.706193] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [490.706293] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1800)
<7> [490.706393] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)
<7> [490.706492] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)
<7> [490.706583] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1906)
<7> [490.706676] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1906)
<7> [490.706771] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [490.706855] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [490.706938] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)
<7> [490.707021] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)
<7> [490.707101] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)
<7> [490.707199] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)
<7> [490.707302] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [490.707403] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1800)
<7> [490.707504] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)
<7> [490.707588] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)
<7> [490.707671] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1906)
<7> [490.707754] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1906)
<7> [490.707836] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [490.707918] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [490.707998] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [490.708083] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [490.708179] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 347710)
<7> [490.708277] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 347710)
<7> [490.708374] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in port_clock (expected 0, found 432000)
<7> [490.708465] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:389:pipe C] fastset requirement not met, forcing full modeset
<7> [490.708562] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xd (total dbuf slices 0xf), mbus joined? no->no
<7> [490.708624] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:389:pipe C] dbuf slices 0x0 -> 0xc, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x4
<7> [490.708715] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:273:plane 1C] ddb ( 0 - 0) -> ( 0 - 2003), size 0 -> 2003
<7> [490.708783] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:383:cursor C] ddb ( 0 - 0) -> (2003 - 2048), size 0 -> 45
<7> [490.708849] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:273:plane 1C] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [490.708912] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:273:plane 1C] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 13, 13, 13, 13, 13, 0, 0, 0, 4, 0
<7> [490.708976] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:273:plane 1C] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 92, 297, 297, 297, 297, 297, 0, 0, 197, 92, 197
<7> [490.709033] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:273:plane 1C] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 183, 456, 456, 456, 456, 456, 0, 0, 198, 183, 198
<7> [490.709104] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:389:pipe C] data rate 1390840 num active planes 1
<7> [490.709222] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [490.709335] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [490.709446] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [490.709555] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [490.709647] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [490.709756] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:389:pipe C] min cdclk: 0 kHz -> 173855 kHz
<7> [490.709872] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [490.709955] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [490.710037] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [490.710140] xe 0000:00:02.0: [drm:intel_find_dpll [xe]] [CRTC:389:pipe C] allocated DPLL 0
<7> [490.710243] xe 0000:00:02.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:389:pipe C] reserving DPLL 0
<7> [490.710353] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:389:pipe C] enable: yes [modeset]
<7> [490.710465] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [490.710567] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: C, pipe bpp: 30, dithering: 0
<7> [490.710652] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [490.710735] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [490.710821] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [490.710910] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [490.710998] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m_n: lanes: 4; data_m: 6329869, data_n: 8388608, link_m: 421991, link_n: 524288, tu: 64
<7> [490.711086] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m2_n2: lanes: 4; data_m: 0, data_n: 0, link_m: 0, link_n: 0, tu: 0
<7> [490.711190] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [490.711295] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [490.711398] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: disabled, panel replay: disabled, selective fetch: disabled
<7> [490.711504] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum hblank: 0
<7> [490.711599] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [490.711686] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 2, length 8
<7> [490.711772] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [490.711852] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [490.711930] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [490.712008] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [490.712085] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [490.712173] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [490.712273] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [490.712374] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: no, fixed rr: yes, vmin: 1906, vmax: 1906, flipline: 1906, pipeline full: 0, guardband: 104 vsync start: 103, vsync end: 97
<7> [490.712477] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1802, vmax vblank: 1802, vmin vtotal: 1906, vmax vtotal: 1906
<7> [490.712569] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [490.712651] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] requested mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [490.712732] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [490.712814] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=347710, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1906 vs=1803-1809 vt=1906, flags=0xa
<7> [490.712897] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x40 0xa
<7> [490.712980] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=347710, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1906 vs=1803-1809 vt=1906, flags=0xa
<7> [490.713064] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 432000, pixel rate 347710, min cdclk 173855, min voltage level 0
<7> [490.713151] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [490.713253] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [490.713353] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe src: 2880x1800+0+0
<7> [490.713451] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [490.713539] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [490.713615] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [490.713694] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: yes, tbt_mode: no
<7> [490.713773] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_hw_state: fracen: no,
<7> [490.713851] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] multiplier: 225, tx_clk_div: 1.
<7> [490.713934] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_rawhw_state:
<7> [490.714018] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] tx: 0x10, cmn: 0x21
<7> [490.714102] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1
<7> [490.714212] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [490.714326] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [490.714439] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [490.714550] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23
<7> [490.714635] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [490.714715] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [490.714792] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [490.714869] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [490.714956] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [490.715035] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [490.715112] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [490.715214] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [490.715314] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [490.715411] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [490.715509] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [490.715600] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [490.715682] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:273:plane 1C] fb: [FB:429] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [490.715765] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [490.715846] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [490.715928] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:303:plane 2C] fb: [NOFB], visible: no
<7> [490.716010] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:333:plane 3C] fb: [NOFB], visible: no
<7> [490.716090] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:363:plane 4C] fb: [NOFB], visible: no
<7> [490.716190] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:373:plane 5C] fb: [NOFB], visible: no
<7> [490.716299] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:383:cursor C] fb: [NOFB], visible: no
<7> [490.716643] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [490.716782] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_C
<7> [490.716923] xe 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x4 (CLKGATE_DIS_DSSDSC=0x8a000000)
<7> [490.717022] xe 0000:00:02.0: [drm:intel_set_cdclk [xe]] Pre changing CDCLK to 192000 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [490.717115] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [490.717232] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=480
<7> [490.717346] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:392:DDI A/PHY A]
<7> [490.717441] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DDI TC1/PHY TC1]
<7> [490.717531] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST A]
<7> [490.717608] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:404:DP-MST B]
<7> [490.717682] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:405:DP-MST C]
<7> [490.717756] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DDI TC2/PHY TC2]
<7> [490.717830] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST A]
<7> [490.717904] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:416:DP-MST B]
<7> [490.717991] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:417:DP-MST C]
<7> [490.718065] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DDI TC3/PHY TC3]
<7> [490.718139] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST A]
<7> [490.718224] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:424:DP-MST B]
<7> [490.718312] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:425:DP-MST C]
<7> [490.718419] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xd
<7> [490.718601] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [490.718710] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [490.720032] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enable DPLL 0 (active 0x4, on? 0) for [CRTC:389:pipe C]
<7> [490.720118] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enabling DPLL 0
<7> [490.722119] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turn panel power on
<7> [490.722224] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 wait for panel power cycle (479 ms remaining)
<7> [490.731358] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [490.731583] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - long
<7> [490.731695] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [490.731835] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [491.208260] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [491.248381] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [491.248536] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 wait for panel power on
<7> [491.248644] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [491.285248] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [491.285418] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - long
<7> [491.285526] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [491.285743] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [491.460023] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [491.460205] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turning VDD on
<7> [491.460321] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [491.462004] xe 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX A/DDI A/PHY A: DPCD: 14 1e 44 c1 00 00 01 c0 02 00 02 00 00 0b 80
<7> [491.463401] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [491.463959] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 05
<7> [491.464717] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [491.465723] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [491.466472] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Clock recovery OK
<7> [491.466587] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Using DP training pattern TPS4
<7> [491.467694] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [491.467804] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Link Training passed at link rate = 432000, lane count = 4
<7> [491.468338] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe C
<7> [491.468519] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [491.468626] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe C
<7> [491.468742] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:393:eDP-1] set backlight PWM = 4518
<7> [491.485430] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] reserved 29491200 bytes of contiguous stolen space for FBC, limit: 1
<7> [491.485547] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] Enabling FBC on [PLANE:273:plane 1C]
<7> [491.501940] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR1
<7> [491.502926] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:393:eDP-1]
<7> [491.503087] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:389:pipe C]
<7> [494.040008] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turning VDD off
<7> [494.040215] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x00000067
<6> [497.256407] [IGT] kms_flip: finished subtest C-eDP1, FAIL
<6> [497.257849] [IGT] kms_flip: finished subtest flip-vs-expired-vblank-interruptible, FAIL
<7> [497.261227] xe 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:431] from all active usage due to RMFB ioctl
<7> [497.261541] xe 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:273:plane 1C] because [FB:431] is removed
<7> [497.261591] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:273:plane 1C] ddb ( 0 - 2003) -> ( 0 - 0), size 2003 -> 0
<7> [497.261711] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:273:plane 1C] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [497.261804] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:273:plane 1C] lines 4, 13, 13, 13, 13, 13, 0, 0, 0, 4, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [497.261917] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:273:plane 1C] blocks 92, 297, 297, 297, 297, 297, 0, 0, 197, 92, 197 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [497.262005] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:273:plane 1C] min_ddb 183, 456, 456, 456, 456, 456, 0, 0, 198, 183, 198 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [497.262124] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:389:pipe C] data rate 0 num active planes 0
<7> [497.262254] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 0 qgv_peak_bw: 38400
<7> [497.262372] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 0 qgv_peak_bw: 38400
<7> [497.262478] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [497.262571] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [497.262683] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 0
<7> [497.263402] xe 0000:00:02.0: [drm:intel_psr_disable_locked [xe]] Disabling PSR1
<7> [497.263536] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turning VDD on
<7> [497.263653] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006f
<7> [497.267673] xe 0000:00:02.0: [drm:__intel_fbc_disable [xe]] Disabling FBC on [PLANE:273:plane 1C]
<7> [497.288056] xe 0000:00:02.0: [drm:compute_baseline_pipe_bpp [xe]] [CONNECTOR:393:eDP-1] Limiting target display pipe bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [497.288207] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:392:DDI A/PHY A][CRTC:151:pipe A] DP link limits: pixel clock 347710 kHz DSC off max lanes 4 max rate 810000 max pipe_bpp 30 min link_bpp 18.0000 max link_bpp 30.0000
<7> [497.288334] xe 0000:00:02.0: [drm:intel_dp_compute_output_format [xe]] DP lane count 4 clock 432000 bpp input 30 compressed 0.0000 HDR no link rate required 1303913 available 1728000
<7> [497.288451] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [497.288565] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:392:DDI A/PHY A] [CRTC:151:pipe A]
<7> [497.288690] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [497.288814] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [497.288962] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [497.289080] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [497.289195] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6329869/8388608 link 421991/524288)
<7> [497.289309] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [497.289413] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [497.289503] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [497.289601] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [497.289708] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)
<7> [497.289815] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)
<7> [497.289939] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)
<7> [497.290044] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)
<7> [497.290148] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [497.290252] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1800)
<7> [497.290355] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)
<7> [497.290449] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)
<7> [497.290534] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1906)
<7> [497.290634] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1906)
<7> [497.290737] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [497.290842] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [497.290943] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)
<7> [497.291046] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)
<7> [497.291146] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)
<7> [497.291245] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)
<7> [497.291344] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [497.291440] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1800)
<7> [497.291524] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)
<7> [497.291612] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)
<7> [497.291714] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1906)
<7> [497.291815] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1906)
<7> [497.291934] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [497.292038] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [497.292142] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [497.292244] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [497.292344] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 347710)
<7> [497.292440] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 347710)
<7> [497.292523] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 0, found 432000)
<7> [497.292609] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [497.292710] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.enable (expected yes, found no)
<7> [497.292811] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.active (expected yes, found no)
<7> [497.292929] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in cpu_transcoder (expected 2, found -1)
<7> [497.293029] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in lane_count (expected 4, found 0)
<7> [497.293129] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in dp_m_n (expected tu 64 data 6329869/8388608 link 421991/524288, found tu 0, data 0/0 link 0/0)
<7> [497.293227] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [497.293316] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [497.293414] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [497.293512] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [497.293610] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [497.293709] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [497.293801] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [497.293906] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [497.294008] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [497.294110] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1800, found 0)
<7> [497.294212] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [497.294312] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [497.294411] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1906, found 0)
<7> [497.294511] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1906, found 0)
<7> [497.294610] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [497.294708] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [497.294796] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [497.294898] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [497.294998] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [497.295097] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [497.295194] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [497.295289] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1800, found 0)
<7> [497.295369] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [497.295449] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [497.295538] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1906, found 0)
<7> [497.295634] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1906, found 0)
<7> [497.295732] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [497.296017] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [497.296114] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [497.296211] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [497.296305] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 347710, found 0)
<7> [497.296399] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 347710, found 0)
<7> [497.296482] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:389:pipe C] fastset requirement not met in port_clock (expected 432000, found 0)
<7> [497.296568] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:389:pipe C] fastset requirement not met, forcing full modeset
<7> [497.296671] xe 0000:00:02.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:389:pipe C] releasing DPLL 0
<7> [497.296838] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xd -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [497.296932] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:151:pipe A] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x4 -> 0x1
<7> [497.297015] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:389:pipe C] dbuf slices 0xc -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x4 -> 0x1
<7> [497.297095] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 4051), size 0 -> 4051
<7> [497.297172] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:145:cursor A] ddb ( 0 - 0) -> (4051 - 4096), size 0 -> 45
<7> [497.297248] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [497.297321] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 13, 13, 13, 13, 13, 0, 0, 0, 3, 0
<7> [497.297396] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 24, 313, 313, 313, 313, 313, 0, 0, 38, 73, 87
<7> [497.297468] xe 0000:00:02.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 28, 346, 346, 346, 346, 346, 0, 0, 39, 82, 88
<7> [497.297534] xe 0000:00:02.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:383:cursor C] ddb (2003 - 2048) -> ( 0 - 0), size 45 -> 0
<7> [497.297615] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:151:pipe A] data rate 1390840 num active planes 1
<7> [497.297721] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [497.297877] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [497.297988] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [497.298100] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [497.298203] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [497.298314] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:151:pipe A] min cdclk: 0 kHz -> 173855 kHz
<7> [497.298426] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:389:pipe C] min cdclk: 173855 kHz -> 0 kHz
<7> [497.298577] xe 0000:00:02.0: [drm:intel_find_dpll [xe]] [CRTC:151:pipe A] allocated DPLL 0
<7> [497.298677] xe 0000:00:02.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:151:pipe A] reserving DPLL 0
<7> [497.298789] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:151:pipe A] enable: yes [modeset]
<7> [497.298924] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [497.299037] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: A, pipe bpp: 30, dithering: 0
<7> [497.299142] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [497.299233] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [497.299328] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [497.299435] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [497.299541] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m_n: lanes: 4; data_m: 6329869, data_n: 8388608, link_m: 421991, link_n: 524288, tu: 64
<7> [497.299647] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m2_n2: lanes: 4; data_m: 0, data_n: 0, link_m: 0, link_n: 0, tu: 0
<7> [497.299741] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [497.299833] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [497.299939] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled
<7> [497.300042] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum hblank: 0
<7> [497.300147] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [497.300249] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 4, length 14
<7> [497.300337] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [497.300425] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [497.300529] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [497.300634] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [497.300736] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [497.300837] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [497.300940] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [497.301043] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: no, fixed rr: yes, vmin: 1906, vmax: 1906, flipline: 1906, pipeline full: 0, guardband: 104 vsync start: 103, vsync end: 97
<7> [497.301145] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1802, vmax vblank: 1802, vmin vtotal: 1906, vmax vtotal: 1906
<7> [497.301258] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [497.301362] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] requested mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [497.301458] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [497.301563] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=347710, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1906 vs=1803-1809 vt=1906, flags=0xa
<7> [497.301664] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x40 0xa
<7> [497.301764] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=347710, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1906 vs=1803-1809 vt=1906, flags=0xa
<7> [497.301969] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 432000, pixel rate 347710, min cdclk 173855, min voltage level 0
<7> [497.302092] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [497.302206] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [497.302311] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe src: 2880x1800+0+0
<7> [497.302410] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [497.302492] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [497.302585] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [497.302682] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: yes, tbt_mode: no
<7> [497.302780] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_hw_state: fracen: no,
<7> [497.302890] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] multiplier: 225, tx_clk_div: 1.
<7> [497.302990] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_rawhw_state:
<7> [497.303088] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] tx: 0x10, cmn: 0x21
<7> [497.303186] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1
<7> [497.303284] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [497.303381] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [497.303463] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [497.303545] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23
<7> [497.303636] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [497.303736] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [497.303843] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [497.303947] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [497.304047] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [497.304146] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [497.304246] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [497.304348] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [497.304449] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [497.304539] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [497.304622] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [497.304711] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [497.304827] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:35:plane 1A] fb: [FB:394] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [497.304945] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [497.305053] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [497.305151] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [497.305248] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [497.305346] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [497.305444] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [497.305541] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [497.305632] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:389:pipe C] enable: no [modeset]
<7> [497.305725] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:273:plane 1C] fb: [NOFB], visible: no
<7> [497.305854] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:303:plane 2C] fb: [NOFB], visible: no
<7> [497.305952] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:333:plane 3C] fb: [NOFB], visible: no
<7> [497.306049] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:363:plane 4C] fb: [NOFB], visible: no
<7> [497.306146] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:373:plane 5C] fb: [NOFB], visible: no
<7> [497.306239] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:383:cursor C] fb: [NOFB], visible: no
<7> [497.306414] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_A
<7> [497.306601] xe 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x1 (CLKGATE_DIS_DSSDSC=0x88000000)
<7> [497.307063] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [497.307181] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [497.511945] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:393:eDP-1] set backlight PWM = 0
<7> [497.512168] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe C
<7> [497.519131] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turn panel power off
<7> [497.519253] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [497.519380] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000003 PP_CONTROL: 0x00000060
<7> [497.519638] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [497.519869] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - short
<7> [497.572038] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [497.572185] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - short
<7> [497.580060] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [497.580970] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disable DPLL 0 (active 0x4, on? 1) for [CRTC:389:pipe C]
<7> [497.581076] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [497.581239] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [497.582106] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disabling DPLL 0
<7> [497.582309] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [497.582480] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:392:DDI A/PHY A]
<7> [497.582589] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DDI TC1/PHY TC1]
<7> [497.582678] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST A]
<7> [497.582761] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:404:DP-MST B]
<7> [497.582855] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:405:DP-MST C]
<7> [497.582949] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DDI TC2/PHY TC2]
<7> [497.583039] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST A]
<7> [497.583123] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:416:DP-MST B]
<7> [497.583190] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:417:DP-MST C]
<7> [497.583256] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DDI TC3/PHY TC3]
<7> [497.583328] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST A]
<7> [497.583403] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:424:DP-MST B]
<7> [497.583476] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:425:DP-MST C]
<7> [497.583579] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [497.583749] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [497.583827] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 8 (mbus joined: yes)
<7> [497.583953] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [497.584860] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:151:pipe A]
<7> [497.584973] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enabling DPLL 0
<7> [497.586939] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turn panel power on
<7> [497.587051] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 wait for panel power cycle (494 ms remaining)
<7> [497.611050] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [497.611177] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - long
<7> [497.611271] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [497.611424] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [498.087935] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [498.148577] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [498.148726] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 wait for panel power on
<7> [498.148852] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [498.185653] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [498.185863] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - long
<7> [498.185972] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [498.186210] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [498.360436] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [498.360610] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turning VDD on
<7> [498.360726] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [498.362441] xe 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX A/DDI A/PHY A: DPCD: 14 1e 44 c1 00 00 01 c0 02 00 02 00 00 0b 80
<7> [498.363802] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [498.364267] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 05
<7> [498.365017] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [498.365990] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [498.366703] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Clock recovery OK
<7> [498.366822] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Using DP training pattern TPS4
<7> [498.367905] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [498.368000] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:393:eDP-1][ENCODER:392:DDI A/PHY A][DPRX] Link Training passed at link rate = 432000, lane count = 4
<7> [498.368503] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe A
<7> [498.368681] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [498.368787] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe A
<7> [498.368904] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:393:eDP-1] set backlight PWM = 4518
<7> [498.402193] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR2
<7> [498.403466] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:393:eDP-1]
<7> [498.403628] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:151:pipe A]
<7> [498.406010] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_C
<7> [498.406110] xe 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x4 (CLKGATE_DIS_DSSDSC=0xa8000000)
<7> [498.406230] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [498.406339] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:389:pipe C]
<7> [498.407393] xe 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0
<6> [498.407747] [IGT] kms_flip: exiting, ret=98
Created at 2026-05-15 00:14:25