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

Result: Fail

i915_display_info12 igt_runner12 results12.json results12-xe-load.json guc_logs12.tar i915_display_info_post_exec12 serial_data12 boot12 dmesg12

DetailValue
Duration 5.54 seconds
Hostname 
shard-lnl-5
Igt-Version 
IGT-Version: 2.4-gd52533be4 (x86_64) (Linux: 7.1.0-rc3-lgci-xe-xe-5055-896e027423be1efc1-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 (5.541s)
Subtest flip-vs-expired-vblank-interruptible: FAIL (28.043s)
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
[440.047488] (kms_flip:12433) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
[440.047571] (kms_flip:12433) CRITICAL: Failed assertion: end - start < 500
[440.047620] (kms_flip:12433) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest C-eDP1 failed.
**** DEBUG ****
[438.030581] (kms_flip:12433) DEBUG: Vblank took 55us
[438.047201] (kms_flip:12433) DEBUG: name = flip
last_ts = 439.590759
last_received_ts = 439.590057
last_seq = 192
current_ts = 439.607422
current_received_ts = 439.606750
current_seq = 193
count = 138
seq_step = 1
[438.047250] (kms_flip:12433) DEBUG: Vblank took 46us
[438.063854] (kms_flip:12433) DEBUG: name = flip
last_ts = 439.607422
last_received_ts = 439.606750
last_seq = 193
current_ts = 439.624054
current_received_ts = 439.623383
current_seq = 194
count = 139
seq_step = 1
[438.063913] (kms_flip:12433) DEBUG: Vblank took 56us
[438.080536] (kms_flip:12433) DEBUG: name = flip
last_ts = 439.624054
last_received_ts = 439.623383
last_seq = 194
current_ts = 439.640747
current_received_ts = 439.640076
current_seq = 195
count = 140
seq_step = 1
[438.080606] (kms_flip:12433) DEBUG: Vblank took 66us
[438.097189] (kms_flip:12433) DEBUG: name = flip
last_ts = 439.640747
last_received_ts = 439.640076
last_seq = 195
current_ts = 439.657410
current_received_ts = 439.656708
current_seq = 196
count = 141
seq_step = 1
[438.097250] (kms_flip:12433) DEBUG: Vblank took 58us
[438.113849] (kms_flip:12433) DEBUG: name = flip
last_ts = 439.657410
last_received_ts = 439.656708
last_seq = 196
current_ts = 439.674042
current_received_ts = 439.673370
current_seq = 197
count = 142
seq_step = 1
[438.113901] (kms_flip:12433) DEBUG: Vblank took 49us
[438.130514] (kms_flip:12433) DEBUG: name = flip
last_ts = 439.674042
last_received_ts = 439.673370
last_seq = 197
current_ts = 439.690704
current_received_ts = 439.690033
current_seq = 198
count = 143
seq_step = 1
[438.130555] (kms_flip:12433) DEBUG: Vblank took 38us
[438.147158] (kms_flip:12433) DEBUG: name = flip
last_ts = 439.690704
last_received_ts = 439.690033
last_seq = 198
current_ts = 439.707367
current_received_ts = 439.706665
current_seq = 199
count = 144
seq_step = 1
[438.147208] (kms_flip:12433) DEBUG: Vblank took 47us
[438.163855] (kms_flip:12433) DEBUG: name = flip
last_ts = 439.707367
last_received_ts = 439.706665
last_seq = 199
current_ts = 439.724030
current_received_ts = 439.723358
current_seq = 200
count = 145
seq_step = 1
[438.163918] (kms_flip:12433) DEBUG: Vblank took 59us
[438.180534] (kms_flip:12433) DEBUG: name = flip
last_ts = 439.724030
last_received_ts = 439.723358
last_seq = 200
current_ts = 439.740692
current_received_ts = 439.740051
current_seq = 201
count = 146
seq_step = 1
[438.180583] (kms_flip:12433) DEBUG: Vblank took 47us
[438.197136] (kms_flip:12433) DEBUG: name = flip
last_ts = 439.740692
last_received_ts = 439.740051
last_seq = 201
current_ts = 439.757355
current_received_ts = 439.756653
current_seq = 202
count = 147
seq_step = 1
[438.197181] (kms_flip:12433) DEBUG: Vblank took 43us
[438.213816] (kms_flip:12433) DEBUG: name = flip
last_ts = 439.757355
last_received_ts = 439.756653
last_seq = 202
current_ts = 439.774017
current_received_ts = 439.773315
current_seq = 203
count = 148
seq_step = 1
[438.213865] (kms_flip:12433) DEBUG: Vblank took 47us
[438.230515] (kms_flip:12433) DEBUG: name = flip
last_ts = 439.774017
last_received_ts = 439.773315
last_seq = 203
current_ts = 439.790680
current_received_ts = 439.790009
current_seq = 204
count = 149
seq_step = 1
[438.230560] (kms_flip:12433) DEBUG: Vblank took 44us
[438.247175] (kms_flip:12433) DEBUG: name = flip
last_ts = 439.790680
last_received_ts = 439.790009
last_seq = 204
current_ts = 439.807343
current_received_ts = 439.806671
current_seq = 205
count = 150
seq_step = 1
[438.247227] (kms_flip:12433) DEBUG: Vblank took 50us
[438.263791] (kms_flip:12433) DEBUG: name = flip
last_ts = 439.807343
last_received_ts = 439.806671
last_seq = 205
current_ts = 439.824005
current_received_ts = 439.823303
current_seq = 206
count = 151
seq_step = 1
[438.263838] (kms_flip:12433) DEBUG: Vblank took 45us
[438.280624] (kms_flip:12433) DEBUG: name = flip
last_ts = 439.824005
last_received_ts = 439.823303
last_seq = 206
current_ts = 439.840668
current_received_ts = 439.839996
current_seq = 207
count = 152
seq_step = 1
[438.280808] (kms_flip:12433) DEBUG: Vblank took 143us
[438.297303] (kms_flip:12433) DEBUG: name = flip
last_ts = 439.840668
last_received_ts = 439.839996
last_seq = 207
current_ts = 439.857330
current_received_ts = 439.856750
current_seq = 208
count = 153
seq_step = 1
[438.297572] (kms_flip:12433) DEBUG: Vblank took 231us
[438.313823] (kms_flip:12433) DEBUG: name = flip
last_ts = 439.857330
last_received_ts = 439.856750
last_seq = 208
current_ts = 439.873993
current_received_ts = 439.873291
current_seq = 209
count = 154
seq_step = 1
[438.313966] (kms_flip:12433) DEBUG: Vblank took 126us
[438.330500] (kms_flip:12433) DEBUG: name = flip
last_ts = 439.873993
last_received_ts = 439.873291
last_seq = 209
current_ts = 439.890656
current_received_ts = 439.889984
current_seq = 210
count = 155
seq_step = 1
[438.330619] (kms_flip:12433) DEBUG: Vblank took 88us
[438.347155] (kms_flip:12433) DEBUG: name = flip
last_ts = 439.890656
last_received_ts = 439.889984
last_seq = 210
current_ts = 439.907318
current_received_ts = 439.906616
current_seq = 211
count = 156
seq_step = 1
[438.347244] (kms_flip:12433) DEBUG: Vblank took 82us
[438.363808] (kms_flip:12433) DEBUG: name = flip
last_ts = 439.907318
last_received_ts = 439.906616
last_seq = 211
current_ts = 439.923981
current_received_ts = 439.923279
current_seq = 212
count = 157
seq_step = 1
[438.363929] (kms_flip:12433) DEBUG: Vblank took 112us
[438.380493] (kms_flip:12433) DEBUG: name = flip
last_ts = 439.923981
last_received_ts = 439.923279
last_seq = 212
current_ts = 439.940643
current_received_ts = 439.939972
current_seq = 213
count = 158
seq_step = 1
[438.380603] (kms_flip:12433) DEBUG: Vblank took 103us
[438.397239] (kms_flip:12433) DEBUG: name = flip
last_ts = 439.940643
last_received_ts = 439.939972
last_seq = 213
current_ts = 439.957306
current_received_ts = 439.956696
current_seq = 214
count = 159
seq_step = 1
[438.397449] (kms_flip:12433) DEBUG: Vblank took 190us
[438.413669] (kms_flip:12433) DEBUG: name = flip
last_ts = 439.957306
last_received_ts = 439.956696
last_seq = 214
current_ts = 439.973938
current_received_ts = 439.973145
current_seq = 215
count = 160
seq_step = 1
[438.413733] (kms_flip:12433) DEBUG: Vblank took 58us
[438.430479] (kms_flip:12433) DEBUG: name = flip
last_ts = 439.973938
last_received_ts = 439.973145
last_seq = 215
current_ts = 439.990601
current_received_ts = 439.989960
current_seq = 216
count = 161
seq_step = 1
[438.430561] (kms_flip:12433) DEBUG: Vblank took 76us
[438.447142] (kms_flip:12433) DEBUG: name = flip
last_ts = 439.990601
last_received_ts = 439.989960
last_seq = 216
current_ts = 440.007294
current_received_ts = 440.006622
current_seq = 217
count = 162
seq_step = 1
[438.447199] (kms_flip:12433) DEBUG: Vblank took 52us
[438.463835] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.007294
last_received_ts = 440.006622
last_seq = 217
current_ts = 440.023926
current_received_ts = 440.023285
current_seq = 218
count = 163
seq_step = 1
[438.463904] (kms_flip:12433) DEBUG: Vblank took 64us
[438.480515] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.023926
last_received_ts = 440.023285
last_seq = 218
current_ts = 440.040588
current_received_ts = 440.039978
current_seq = 219
count = 164
seq_step = 1
[438.480584] (kms_flip:12433) DEBUG: Vblank took 66us
[438.497179] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.040588
last_received_ts = 440.039978
last_seq = 219
current_ts = 440.057251
current_received_ts = 440.056641
current_seq = 220
count = 165
seq_step = 1
[438.497251] (kms_flip:12433) DEBUG: Vblank took 69us
[438.513777] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.057251
last_received_ts = 440.056641
last_seq = 220
current_ts = 440.073914
current_received_ts = 440.073242
current_seq = 221
count = 166
seq_step = 1
[438.513839] (kms_flip:12433) DEBUG: Vblank took 57us
[438.530470] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.073914
last_received_ts = 440.073242
last_seq = 221
current_ts = 440.090576
current_received_ts = 440.089935
current_seq = 222
count = 167
seq_step = 1
[438.530528] (kms_flip:12433) DEBUG: Vblank took 56us
[438.547098] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.090576
last_received_ts = 440.089935
last_seq = 222
current_ts = 440.107239
current_received_ts = 440.106537
current_seq = 223
count = 168
seq_step = 1
[438.547147] (kms_flip:12433) DEBUG: Vblank took 45us
[438.563740] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.107239
last_received_ts = 440.106537
last_seq = 223
current_ts = 440.123901
current_received_ts = 440.123199
current_seq = 224
count = 169
seq_step = 1
[438.563787] (kms_flip:12433) DEBUG: Vblank took 45us
[438.580462] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.123901
last_received_ts = 440.123199
last_seq = 224
current_ts = 440.140564
current_received_ts = 440.139893
current_seq = 225
count = 170
seq_step = 1
[438.580518] (kms_flip:12433) DEBUG: Vblank took 52us
[438.597136] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.140564
last_received_ts = 440.139893
last_seq = 225
current_ts = 440.157227
current_received_ts = 440.156586
current_seq = 226
count = 171
seq_step = 1
[438.597201] (kms_flip:12433) DEBUG: Vblank took 60us
[438.613673] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.157227
last_received_ts = 440.156586
last_seq = 226
current_ts = 440.173889
current_received_ts = 440.173126
current_seq = 227
count = 172
seq_step = 1
[438.613790] (kms_flip:12433) DEBUG: Vblank took 115us
[438.630319] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.173889
last_received_ts = 440.173126
last_seq = 227
current_ts = 440.190552
current_received_ts = 440.189758
current_seq = 228
count = 173
seq_step = 1
[438.630365] (kms_flip:12433) DEBUG: Vblank took 45us
[438.647093] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.190552
last_received_ts = 440.189758
last_seq = 228
current_ts = 440.207214
current_received_ts = 440.206543
current_seq = 229
count = 174
seq_step = 1
[438.647139] (kms_flip:12433) DEBUG: Vblank took 44us
[438.663741] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.207214
last_received_ts = 440.206543
last_seq = 229
current_ts = 440.223877
current_received_ts = 440.223175
current_seq = 230
count = 175
seq_step = 1
[438.663792] (kms_flip:12433) DEBUG: Vblank took 48us
[438.680444] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.223877
last_received_ts = 440.223175
last_seq = 230
current_ts = 440.240540
current_received_ts = 440.239868
current_seq = 231
count = 176
seq_step = 1
[438.680505] (kms_flip:12433) DEBUG: Vblank took 58us
[438.697084] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.240540
last_received_ts = 440.239868
last_seq = 231
current_ts = 440.257202
current_received_ts = 440.256500
current_seq = 232
count = 177
seq_step = 1
[438.697137] (kms_flip:12433) DEBUG: Vblank took 50us
[438.713735] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.257202
last_received_ts = 440.256500
last_seq = 232
current_ts = 440.273865
current_received_ts = 440.273163
current_seq = 233
count = 178
seq_step = 1
[438.713788] (kms_flip:12433) DEBUG: Vblank took 50us
[438.730309] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.273865
last_received_ts = 440.273163
last_seq = 233
current_ts = 440.290527
current_received_ts = 440.289734
current_seq = 234
count = 179
seq_step = 1
[438.730349] (kms_flip:12433) DEBUG: Vblank took 37us
[438.747086] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.290527
last_received_ts = 440.289734
last_seq = 234
current_ts = 440.307190
current_received_ts = 440.306519
current_seq = 235
count = 180
seq_step = 1
[438.747145] (kms_flip:12433) DEBUG: Vblank took 56us
[438.763715] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.307190
last_received_ts = 440.306519
last_seq = 235
current_ts = 440.323853
current_received_ts = 440.323120
current_seq = 236
count = 181
seq_step = 1
[438.763761] (kms_flip:12433) DEBUG: Vblank took 44us
[438.780393] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.323853
last_received_ts = 440.323120
last_seq = 236
current_ts = 440.340515
current_received_ts = 440.339813
current_seq = 237
count = 182
seq_step = 1
[438.780443] (kms_flip:12433) DEBUG: Vblank took 48us
[438.797056] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.340515
last_received_ts = 440.339813
last_seq = 237
current_ts = 440.357178
current_received_ts = 440.356476
current_seq = 238
count = 183
seq_step = 1
[438.797113] (kms_flip:12433) DEBUG: Vblank took 55us
[438.813710] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.357178
last_received_ts = 440.356476
last_seq = 238
current_ts = 440.373810
current_received_ts = 440.373108
current_seq = 239
count = 184
seq_step = 1
[438.813764] (kms_flip:12433) DEBUG: Vblank took 51us
[438.830393] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.373810
last_received_ts = 440.373108
last_seq = 239
current_ts = 440.390503
current_received_ts = 440.389801
current_seq = 240
count = 185
seq_step = 1
[438.830441] (kms_flip:12433) DEBUG: Vblank took 47us
[438.847039] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.390503
last_received_ts = 440.389801
last_seq = 240
current_ts = 440.407166
current_received_ts = 440.406433
current_seq = 241
count = 186
seq_step = 1
[438.847082] (kms_flip:12433) DEBUG: Vblank took 40us
[438.863702] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.407166
last_received_ts = 440.406433
last_seq = 241
current_ts = 440.423798
current_received_ts = 440.423096
current_seq = 242
count = 187
seq_step = 1
[438.863767] (kms_flip:12433) DEBUG: Vblank took 63us
[438.880392] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.423798
last_received_ts = 440.423096
last_seq = 242
current_ts = 440.440460
current_received_ts = 440.439789
current_seq = 243
count = 188
seq_step = 1
[438.880463] (kms_flip:12433) DEBUG: Vblank took 68us
[438.897058] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.440460
last_received_ts = 440.439789
last_seq = 243
current_ts = 440.457123
current_received_ts = 440.456451
current_seq = 244
count = 189
seq_step = 1
[438.897110] (kms_flip:12433) DEBUG: Vblank took 49us
[438.913712] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.457123
last_received_ts = 440.456451
last_seq = 244
current_ts = 440.473785
current_received_ts = 440.473114
current_seq = 245
count = 190
seq_step = 1
[438.913760] (kms_flip:12433) DEBUG: Vblank took 45us
[438.930404] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.473785
last_received_ts = 440.473114
last_seq = 245
current_ts = 440.490448
current_received_ts = 440.489807
current_seq = 246
count = 191
seq_step = 1
[438.930461] (kms_flip:12433) DEBUG: Vblank took 54us
[438.947028] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.490448
last_received_ts = 440.489807
last_seq = 246
current_ts = 440.507111
current_received_ts = 440.506409
current_seq = 247
count = 192
seq_step = 1
[438.947077] (kms_flip:12433) DEBUG: Vblank took 46us
[438.963710] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.507111
last_received_ts = 440.506409
last_seq = 247
current_ts = 440.523773
current_received_ts = 440.523102
current_seq = 248
count = 193
seq_step = 1
[438.963762] (kms_flip:12433) DEBUG: Vblank took 49us
[438.980359] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.523773
last_received_ts = 440.523102
last_seq = 248
current_ts = 440.540436
current_received_ts = 440.539734
current_seq = 249
count = 194
seq_step = 1
[438.980411] (kms_flip:12433) DEBUG: Vblank took 48us
[438.997053] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.540436
last_received_ts = 440.539734
last_seq = 249
current_ts = 440.557098
current_received_ts = 440.556427
current_seq = 250
count = 195
seq_step = 1
[438.997106] (kms_flip:12433) DEBUG: Vblank took 50us
[439.013672] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.557098
last_received_ts = 440.556427
last_seq = 250
current_ts = 440.573761
current_received_ts = 440.573059
current_seq = 251
count = 196
seq_step = 1
[439.013726] (kms_flip:12433) DEBUG: Vblank took 51us
[439.030380] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.573761
last_received_ts = 440.573059
last_seq = 251
current_ts = 440.590424
current_received_ts = 440.589752
current_seq = 252
count = 197
seq_step = 1
[439.030426] (kms_flip:12433) DEBUG: Vblank took 43us
[439.047028] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.590424
last_received_ts = 440.589752
last_seq = 252
current_ts = 440.607086
current_received_ts = 440.606415
current_seq = 253
count = 198
seq_step = 1
[439.047078] (kms_flip:12433) DEBUG: Vblank took 47us
[439.063680] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.607086
last_received_ts = 440.606415
last_seq = 253
current_ts = 440.623749
current_received_ts = 440.623047
current_seq = 254
count = 199
seq_step = 1
[439.063740] (kms_flip:12433) DEBUG: Vblank took 55us
[439.080351] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.623749
last_received_ts = 440.623047
last_seq = 254
current_ts = 440.640411
current_received_ts = 440.639709
current_seq = 255
count = 200
seq_step = 1
[439.080403] (kms_flip:12433) DEBUG: Vblank took 49us
[439.097039] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.640411
last_received_ts = 440.639709
last_seq = 255
current_ts = 440.657074
current_received_ts = 440.656403
current_seq = 256
count = 201
seq_step = 1
[439.097088] (kms_flip:12433) DEBUG: Vblank took 47us
[439.113699] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.657074
last_received_ts = 440.656403
last_seq = 256
current_ts = 440.673737
current_received_ts = 440.673065
current_seq = 257
count = 202
seq_step = 1
[439.113756] (kms_flip:12433) DEBUG: Vblank took 54us
[439.130357] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.673737
last_received_ts = 440.673065
last_seq = 257
current_ts = 440.690399
current_received_ts = 440.689697
current_seq = 258
count = 203
seq_step = 1
[439.130408] (kms_flip:12433) DEBUG: Vblank took 46us
[439.147012] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.690399
last_received_ts = 440.689697
last_seq = 258
current_ts = 440.707062
current_received_ts = 440.706360
current_seq = 259
count = 204
seq_step = 1
[439.147060] (kms_flip:12433) DEBUG: Vblank took 45us
[439.163672] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.707062
last_received_ts = 440.706360
last_seq = 259
current_ts = 440.723724
current_received_ts = 440.723022
current_seq = 260
count = 205
seq_step = 1
[439.163722] (kms_flip:12433) DEBUG: Vblank took 48us
[439.180366] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.723724
last_received_ts = 440.723022
last_seq = 260
current_ts = 440.740387
current_received_ts = 440.739716
current_seq = 261
count = 206
seq_step = 1
[439.180426] (kms_flip:12433) DEBUG: Vblank took 56us
[439.196987] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.740387
last_received_ts = 440.739716
last_seq = 261
current_ts = 440.757050
current_received_ts = 440.756348
current_seq = 262
count = 207
seq_step = 1
[439.197042] (kms_flip:12433) DEBUG: Vblank took 52us
[439.213717] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.757050
last_received_ts = 440.756348
last_seq = 262
current_ts = 440.773682
current_received_ts = 440.773071
current_seq = 263
count = 208
seq_step = 1
[439.213778] (kms_flip:12433) DEBUG: Vblank took 57us
[439.230385] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.773682
last_received_ts = 440.773071
last_seq = 263
current_ts = 440.790344
current_received_ts = 440.789734
current_seq = 264
count = 209
seq_step = 1
[439.230445] (kms_flip:12433) DEBUG: Vblank took 56us
[439.247016] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.790344
last_received_ts = 440.789734
last_seq = 264
current_ts = 440.807037
current_received_ts = 440.806366
current_seq = 265
count = 210
seq_step = 1
[439.247079] (kms_flip:12433) DEBUG: Vblank took 58us
[439.263653] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.807037
last_received_ts = 440.806366
last_seq = 265
current_ts = 440.823669
current_received_ts = 440.822998
current_seq = 266
count = 211
seq_step = 1
[439.263713] (kms_flip:12433) DEBUG: Vblank took 57us
[439.280336] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.823669
last_received_ts = 440.822998
last_seq = 266
current_ts = 440.840363
current_received_ts = 440.839661
current_seq = 267
count = 212
seq_step = 1
[439.280388] (kms_flip:12433) DEBUG: Vblank took 49us
[439.296964] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.840363
last_received_ts = 440.839661
last_seq = 267
current_ts = 440.856995
current_received_ts = 440.856293
current_seq = 268
count = 213
seq_step = 1
[439.297015] (kms_flip:12433) DEBUG: Vblank took 47us
[439.313654] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.856995
last_received_ts = 440.856293
last_seq = 268
current_ts = 440.873657
current_received_ts = 440.872986
current_seq = 269
count = 214
seq_step = 1
[439.313731] (kms_flip:12433) DEBUG: Vblank took 72us
[439.330292] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.873657
last_received_ts = 440.872986
last_seq = 269
current_ts = 440.890320
current_received_ts = 440.889618
current_seq = 270
count = 215
seq_step = 1
[439.330348] (kms_flip:12433) DEBUG: Vblank took 52us
[439.346968] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.890320
last_received_ts = 440.889618
last_seq = 270
current_ts = 440.906982
current_received_ts = 440.906281
current_seq = 271
count = 216
seq_step = 1
[439.347025] (kms_flip:12433) DEBUG: Vblank took 54us
[439.363626] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.906982
last_received_ts = 440.906281
last_seq = 271
current_ts = 440.923645
current_received_ts = 440.922943
current_seq = 272
count = 217
seq_step = 1
[439.363692] (kms_flip:12433) DEBUG: Vblank took 63us
[439.380281] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.923645
last_received_ts = 440.922943
last_seq = 272
current_ts = 440.940308
current_received_ts = 440.939606
current_seq = 273
count = 218
seq_step = 1
[439.380335] (kms_flip:12433) DEBUG: Vblank took 51us
[439.396935] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.940308
last_received_ts = 440.939606
last_seq = 273
current_ts = 440.956970
current_received_ts = 440.956238
current_seq = 274
count = 219
seq_step = 1
[439.396991] (kms_flip:12433) DEBUG: Vblank took 52us
[439.413676] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.956970
last_received_ts = 440.956238
last_seq = 274
current_ts = 440.973633
current_received_ts = 440.972992
current_seq = 275
count = 220
seq_step = 1
[439.413802] (kms_flip:12433) DEBUG: Vblank took 122us
[439.430290] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.973633
last_received_ts = 440.972992
last_seq = 275
current_ts = 440.990295
current_received_ts = 440.989594
current_seq = 276
count = 221
seq_step = 1
[439.430352] (kms_flip:12433) DEBUG: Vblank took 58us
[439.446922] (kms_flip:12433) DEBUG: name = flip
last_ts = 440.990295
last_received_ts = 440.989594
last_seq = 276
current_ts = 441.006958
current_received_ts = 441.006226
current_seq = 277
count = 222
seq_step = 1
[439.446984] (kms_flip:12433) DEBUG: Vblank took 58us
[439.463614] (kms_flip:12433) DEBUG: name = flip
last_ts = 441.006958
last_received_ts = 441.006226
last_seq = 277
current_ts = 441.023621
current_received_ts = 441.022919
current_seq = 278
count = 223
seq_step = 1
[439.463664] (kms_flip:12433) DEBUG: Vblank took 48us
[439.480295] (kms_flip:12433) DEBUG: name = flip
last_ts = 441.023621
last_received_ts = 441.022919
last_seq = 278
current_ts = 441.040283
current_received_ts = 441.039612
current_seq = 279
count = 224
seq_step = 1
[439.480348] (kms_flip:12433) DEBUG: Vblank took 50us
[439.496942] (kms_flip:12433) DEBUG: name = flip
last_ts = 441.040283
last_received_ts = 441.039612
last_seq = 279
current_ts = 441.056946
current_received_ts = 441.056244
current_seq = 280
count = 225
seq_step = 1
[439.496997] (kms_flip:12433) DEBUG: Vblank took 52us
[439.513634] (kms_flip:12433) DEBUG: name = flip
last_ts = 441.056946
last_received_ts = 441.056244
last_seq = 280
current_ts = 441.073608
current_received_ts = 441.072937
current_seq = 281
count = 226
seq_step = 1
[439.513688] (kms_flip:12433) DEBUG: Vblank took 51us
[439.530339] (kms_flip:12433) DEBUG: name = flip
last_ts = 441.073608
last_received_ts = 441.072937
last_seq = 281
current_ts = 441.090271
current_received_ts = 441.089630
current_seq = 282
count = 227
seq_step = 1
[439.530410] (kms_flip:12433) DEBUG: Vblank took 66us
[439.546972] (kms_flip:12433) DEBUG: name = flip
last_ts = 441.090271
last_received_ts = 441.089630
last_seq = 282
current_ts = 441.106934
current_received_ts = 441.106262
current_seq = 283
count = 228
seq_step = 1
[439.547025] (kms_flip:12433) DEBUG: Vblank took 49us
[439.563607] (kms_flip:12433) DEBUG: name = flip
last_ts = 441.106934
last_received_ts = 441.106262
last_seq = 283
current_ts = 441.123596
current_received_ts = 441.122894
current_seq = 284
count = 229
seq_step = 1
[439.563657] (kms_flip:12433) DEBUG: Vblank took 47us
[439.580273] (kms_flip:12433) DEBUG: name = flip
last_ts = 441.123596
last_received_ts = 441.122894
last_seq = 284
current_ts = 441.140259
current_received_ts = 441.139557
current_seq = 285
count = 230
seq_step = 1
[439.580325] (kms_flip:12433) DEBUG: Vblank took 49us
[439.596862] (kms_flip:12433) DEBUG: name = flip
last_ts = 441.140259
last_received_ts = 441.139557
last_seq = 285
current_ts = 441.156921
current_received_ts = 441.156158
current_seq = 286
count = 231
seq_step = 1
[439.596917] (kms_flip:12433) DEBUG: Vblank took 53us
[439.613606] (kms_flip:12433) DEBUG: name = flip
last_ts = 441.156921
last_received_ts = 441.156158
last_seq = 286
current_ts = 441.173553
current_received_ts = 441.172882
current_seq = 287
count = 232
seq_step = 1
[439.613668] (kms_flip:12433) DEBUG: Vblank took 57us
[439.630292] (kms_flip:12433) DEBUG: name = flip
last_ts = 441.173553
last_received_ts = 441.172882
last_seq = 287
current_ts = 441.190247
current_received_ts = 441.189575
current_seq = 288
count = 233
seq_step = 1
[439.630345] (kms_flip:12433) DEBUG: Vblank took 50us
[439.646910] (kms_flip:12433) DEBUG: name = flip
last_ts = 441.190247
last_received_ts = 441.189575
last_seq = 288
current_ts = 441.206879
current_received_ts = 441.206177
current_seq = 289
count = 234
seq_step = 1
[439.646963] (kms_flip:12433) DEBUG: Vblank took 50us
[439.663595] (kms_flip:12433) DEBUG: name = flip
last_ts = 441.206879
last_received_ts = 441.206177
last_seq = 289
current_ts = 441.223572
current_received_ts = 441.222870
current_seq = 290
count = 235
seq_step = 1
[439.663645] (kms_flip:12433) DEBUG: Vblank took 47us
[439.680305] (kms_flip:12433) DEBUG: name = flip
last_ts = 441.223572
last_received_ts = 441.222870
last_seq = 290
current_ts = 441.240204
current_received_ts = 441.239563
current_seq = 291
count = 236
seq_step = 1
[439.680360] (kms_flip:12433) DEBUG: Vblank took 51us
[439.696918] (kms_flip:12433) DEBUG: name = flip
last_ts = 441.240204
last_received_ts = 441.239563
last_seq = 291
current_ts = 441.256866
current_received_ts = 441.256195
current_seq = 292
count = 237
seq_step = 1
[439.696963] (kms_flip:12433) DEBUG: Vblank took 42us
[439.713580] (kms_flip:12433) DEBUG: name = flip
last_ts = 441.256866
last_received_ts = 441.256195
last_seq = 292
current_ts = 441.273560
current_received_ts = 441.272858
current_seq = 293
count = 238
seq_step = 1
[439.713632] (kms_flip:12433) DEBUG: Vblank took 50us
[439.730263] (kms_flip:12433) DEBUG: name = flip
last_ts = 441.273560
last_received_ts = 441.272858
last_seq = 293
current_ts = 441.290192
current_received_ts = 441.289520
current_seq = 294
count = 239
seq_step = 1
[439.730316] (kms_flip:12433) DEBUG: Vblank took 49us
[439.746805] (kms_flip:12433) DEBUG: name = flip
last_ts = 441.290192
last_received_ts = 441.289520
last_seq = 294
current_ts = 441.306854
current_received_ts = 441.306061
current_seq = 295
count = 240
seq_step = 1
[439.746857] (kms_flip:12433) DEBUG: Vblank took 48us
[439.763482] (kms_flip:12433) DEBUG: name = flip
last_ts = 441.306854
last_received_ts = 441.306061
last_seq = 295
current_ts = 441.323517
current_received_ts = 441.322754
current_seq = 296
count = 241
seq_step = 1
[439.763537] (kms_flip:12433) DEBUG: Vblank took 52us
[439.780257] (kms_flip:12433) DEBUG: name = flip
last_ts = 441.323517
last_received_ts = 441.322754
last_seq = 296
current_ts = 441.340179
current_received_ts = 441.339508
current_seq = 297
count = 242
seq_step = 1
[439.780312] (kms_flip:12433) DEBUG: Vblank took 52us
[439.796896] (kms_flip:12433) DEBUG: name = flip
last_ts = 441.340179
last_received_ts = 441.339508
last_seq = 297
current_ts = 441.356842
current_received_ts = 441.356140
current_seq = 298
count = 243
seq_step = 1
[439.796946] (kms_flip:12433) DEBUG: Vblank took 47us
[439.813576] (kms_flip:12433) DEBUG: name = flip
last_ts = 441.356842
last_received_ts = 441.356140
last_seq = 298
current_ts = 441.373505
current_received_ts = 441.372833
current_seq = 299
count = 244
seq_step = 1
[439.813633] (kms_flip:12433) DEBUG: Vblank took 53us
[439.830259] (kms_flip:12433) DEBUG: name = flip
last_ts = 441.373505
last_received_ts = 441.372833
last_seq = 299
current_ts = 441.390167
current_received_ts = 441.389496
current_seq = 300
count = 245
seq_step = 1
[439.830321] (kms_flip:12433) DEBUG: Vblank took 58us
[439.846896] (kms_flip:12433) DEBUG: name = flip
last_ts = 441.390167
last_received_ts = 441.389496
last_seq = 300
current_ts = 441.406830
current_received_ts = 441.406128
current_seq = 301
count = 246
seq_step = 1
[439.846946] (kms_flip:12433) DEBUG: Vblank took 47us
[439.863539] (kms_flip:12433) DEBUG: name = flip
last_ts = 441.406830
last_received_ts = 441.406128
last_seq = 301
current_ts = 441.423492
current_received_ts = 441.422791
current_seq = 302
count = 247
seq_step = 1
[439.863589] (kms_flip:12433) DEBUG: Vblank took 48us
[439.880242] (kms_flip:12433) DEBUG: name = flip
last_ts = 441.423492
last_received_ts = 441.422791
last_seq = 302
current_ts = 441.440155
current_received_ts = 441.439484
current_seq = 303
count = 248
seq_step = 1
[439.880301] (kms_flip:12433) DEBUG: Vblank took 56us
[439.896860] (kms_flip:12433) DEBUG: name = flip
last_ts = 441.440155
last_received_ts = 441.439484
last_seq = 303
current_ts = 441.456818
current_received_ts = 441.456085
current_seq = 304
count = 249
seq_step = 1
[439.896908] (kms_flip:12433) DEBUG: Vblank took 45us
[439.913542] (kms_flip:12433) DEBUG: name = flip
last_ts = 441.456818
last_received_ts = 441.456085
last_seq = 304
current_ts = 441.473480
current_received_ts = 441.472778
current_seq = 305
count = 250
seq_step = 1
[439.913599] (kms_flip:12433) DEBUG: Vblank took 54us
[439.930202] (kms_flip:12433) DEBUG: name = flip
last_ts = 441.473480
last_received_ts = 441.472778
last_seq = 305
current_ts = 441.490143
current_received_ts = 441.489441
current_seq = 306
count = 251
seq_step = 1
[439.930262] (kms_flip:12433) DEBUG: Vblank took 57us
[439.946873] (kms_flip:12433) DEBUG: name = flip
last_ts = 441.490143
last_received_ts = 441.489441
last_seq = 306
current_ts = 441.506805
current_received_ts = 441.506104
current_seq = 307
count = 252
seq_step = 1
[439.946947] (kms_flip:12433) DEBUG: Vblank took 71us
[439.963535] (kms_flip:12433) DEBUG: name = flip
last_ts = 441.506805
last_received_ts = 441.506104
last_seq = 307
current_ts = 441.523468
current_received_ts = 441.522766
current_seq = 308
count = 253
seq_step = 1
[439.963584] (kms_flip:12433) DEBUG: Vblank took 47us
[439.980196] (kms_flip:12433) DEBUG: name = flip
last_ts = 441.523468
last_received_ts = 441.522766
last_seq = 308
current_ts = 441.540131
current_received_ts = 441.539429
current_seq = 309
count = 254
seq_step = 1
[439.980245] (kms_flip:12433) DEBUG: Vblank took 46us
[439.996873] (kms_flip:12433) DEBUG: name = flip
last_ts = 441.540131
last_received_ts = 441.539429
last_seq = 309
current_ts = 441.556793
current_received_ts = 441.556091
current_seq = 310
count = 255
seq_step = 1
[439.996928] (kms_flip:12433) DEBUG: Vblank took 52us
[440.013556] (kms_flip:12433) DEBUG: name = flip
last_ts = 441.556793
last_received_ts = 441.556091
last_seq = 310
current_ts = 441.573456
current_received_ts = 441.572784
current_seq = 311
count = 256
seq_step = 1
[440.013609] (kms_flip:12433) DEBUG: Vblank took 48us
[440.030227] (kms_flip:12433) DEBUG: name = flip
last_ts = 441.573456
last_received_ts = 441.572784
last_seq = 311
current_ts = 441.590118
current_received_ts = 441.589447
current_seq = 312
count = 257
seq_step = 1
[440.030281] (kms_flip:12433) DEBUG: Vblank took 51us
[440.046801] (kms_flip:12433) DEBUG: name = flip
last_ts = 441.590118
last_received_ts = 441.589447
last_seq = 312
current_ts = 441.606781
current_received_ts = 441.605988
current_seq = 313
count = 258
seq_step = 1
[440.047475] (kms_flip:12433) DEBUG: Vblank took 657us
[440.047488] (kms_flip:12433) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
[440.047571] (kms_flip:12433) CRITICAL: Failed assertion: end - start < 500
[440.047620] (kms_flip:12433) CRITICAL: Last errno: 4, Interrupted system call
[440.066046] (kms_flip:12433) igt_core-INFO: Stack trace:
[440.078317] (kms_flip:12433) igt_core-INFO:   #0 ../lib/igt_core.c:2074 __igt_fail_assert()
[440.079212] (kms_flip:12433) igt_core-INFO:   #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
[440.079265] (kms_flip:12433) igt_core-INFO:   #2 ../tests/kms_flip.c:1920 run_test()
[440.079290] (kms_flip:12433) igt_core-INFO:   #3 ../tests/kms_flip.c:2220 __igt_unique____real_main2105()
[440.079315] (kms_flip:12433) igt_core-INFO:   #4 ../tests/kms_flip.c:2105 main()
[440.082233] (kms_flip:12433) igt_core-INFO:   #5 [__libc_init_first+0x8a]
[440.082773] (kms_flip:12433) igt_core-INFO:   #6 [__libc_start_main+0x8b]
[440.082928] (kms_flip:12433) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest C-eDP1: FAIL (5.541s)
Subtest flip-vs-expired-vblank-interruptible: FAIL (28.043s)
[441.300004] (kms_flip:12433) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2352:
[441.300088] (kms_flip:12433) igt_kms-CRITICAL: Failed assertion: found_it
[441.300131] (kms_flip:12433) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor
[441.300164] (kms_flip:12433) igt_kms-CRITICAL: DPMS property not found on 393
Dmesg
<6> [526.429593] [IGT] kms_flip: starting dynamic subtest C-eDP1
<7> [526.432586] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:431]
<7> [526.434453] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:432]
<7> [526.443345] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [526.443751] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [526.444065] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [526.494080] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [526.494172] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [526.494311] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [526.494595] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:270:pipe B]
<7> [526.494659] 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> [526.494764] 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> [526.494860] 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> [526.494955] 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> [526.495049] 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> [526.495142] 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> [526.495234] 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> [526.495330] 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> [526.495448] 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> [526.495568] 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> [526.495676] 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> [526.495763] 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> [526.495850] 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> [526.495945] 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> [526.496036] 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> [526.496128] 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> [526.496232] 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> [526.496329] 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> [526.496436] 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> [526.496543] 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> [526.496646] 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> [526.496731] 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> [526.496812] 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> [526.496893] 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> [526.496972] 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> [526.497050] 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> [526.497128] 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> [526.497207] 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> [526.497286] 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> [526.497459] 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> [526.497570] 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> [526.497671] 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> [526.497755] 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> [526.497842] 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> [526.497931] 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> [526.498020] 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> [526.498105] 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> [526.498185] 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> [526.498264] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:270:pipe B] fastset requirement not met, forcing full modeset
<7> [526.498353] xe 0000:00:02.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:270:pipe B] releasing DPLL 0
<7> [526.498486] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [526.498577] 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> [526.498674] 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> [526.498736] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:270:pipe B] min cdclk: 173855 kHz -> 0 kHz
<7> [526.498822] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [526.498907] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 153600 kHz, actual 153600 kHz
<7> [526.498991] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [526.499098] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:270:pipe B] enable: no [modeset]
<7> [526.499192] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:154:plane 1B] fb: [NOFB], visible: no
<7> [526.499281] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:184:plane 2B] fb: [NOFB], visible: no
<7> [526.499379] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:214:plane 3B] fb: [NOFB], visible: no
<7> [526.499486] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:244:plane 4B] fb: [NOFB], visible: no
<7> [526.499595] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:254:plane 5B] fb: [NOFB], visible: no
<7> [526.499691] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:264:cursor B] fb: [NOFB], visible: no
<7> [526.499886] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [526.499991] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [526.707529] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:393:eDP-1] set backlight PWM = 0
<7> [526.708132] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe B
<7> [526.709385] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turn panel power off
<7> [526.709820] 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> [526.710214] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [526.761436] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [526.761998] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disable DPLL 0 (active 0x2, on? 1) for [CRTC:270:pipe B]
<7> [526.762314] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [526.762871] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - short
<7> [526.763547] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [526.763739] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disabling DPLL 0
<7> [526.764358] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [526.765053] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:392:DDI A/PHY A]
<7> [526.765546] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DDI TC1/PHY TC1]
<7> [526.765978] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST A]
<7> [526.766408] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:404:DP-MST B]
<7> [526.766877] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:405:DP-MST C]
<7> [526.767330] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DDI TC2/PHY TC2]
<7> [526.767793] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST A]
<7> [526.768190] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:416:DP-MST B]
<7> [526.768626] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:417:DP-MST C]
<7> [526.769014] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DDI TC3/PHY TC3]
<7> [526.769420] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST A]
<7> [526.769817] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:424:DP-MST B]
<7> [526.770187] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:425:DP-MST C]
<7> [526.770553] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:393:eDP-1]
<7> [526.770974] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [526.771293] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [526.771694] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [526.772263] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_B
<7> [526.772761] 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> [526.773303] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [526.773861] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:270:pipe B]
<7> [526.774301] 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> [526.774846] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [526.775222] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=384
<7> [526.776129] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:389:pipe C]
<7> [526.776530] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:389:pipe C]
<7> [526.776603] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:393:eDP-1]
<7> [526.776812] 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> [526.777217] 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> [526.777577] 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> [526.777907] xe 0000:00:02.0: [drm:intel_psr_compute_config [xe]] PSR2 not supported in transcoder C
<7> [526.778155] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:389:pipe C] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [526.778468] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:392:DDI A/PHY A] [CRTC:389:pipe C]
<7> [526.778812] 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> [526.779095] 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> [526.779392] 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> [526.779715] 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> [526.779983] 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> [526.780231] 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> [526.780478] 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> [526.780693] 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> [526.780900] 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> [526.781141] 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> [526.781353] 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> [526.781600] 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> [526.781788] 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> [526.781960] 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> [526.782128] 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> [526.782289] 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> [526.782468] 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> [526.782621] 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> [526.782791] 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> [526.782956] 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> [526.783109] 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> [526.783244] 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> [526.783382] 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> [526.783514] 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> [526.783653] 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> [526.783784] 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> [526.783905] 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> [526.784028] 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> [526.784145] 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> [526.784257] 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> [526.784370] 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> [526.784506] 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> [526.784624] 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> [526.784733] 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> [526.784840] 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> [526.784945] 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> [526.785049] 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> [526.785147] 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> [526.785244] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:389:pipe C] fastset requirement not met, forcing full modeset
<7> [526.785361] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xd (total dbuf slices 0xf), mbus joined? no->no
<7> [526.785470] 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> [526.785584] 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> [526.785660] 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> [526.785727] 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> [526.785790] 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> [526.785852] 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> [526.785912] 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> [526.785988] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:389:pipe C] data rate 1390840 num active planes 1
<7> [526.786087] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [526.786179] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [526.786266] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [526.786350] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [526.786502] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [526.786634] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:389:pipe C] min cdclk: 0 kHz -> 173855 kHz
<7> [526.786748] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [526.786828] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [526.786909] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [526.787006] xe 0000:00:02.0: [drm:intel_find_dpll [xe]] [CRTC:389:pipe C] allocated DPLL 0
<7> [526.787087] xe 0000:00:02.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:389:pipe C] reserving DPLL 0
<7> [526.787170] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:389:pipe C] enable: yes [modeset]
<7> [526.787259] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [526.787379] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: C, pipe bpp: 30, dithering: 0
<7> [526.787480] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [526.787575] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [526.787664] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [526.787743] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [526.787823] 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> [526.787902] 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> [526.787982] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [526.788059] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [526.788137] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: disabled, panel replay: disabled, selective fetch: disabled
<7> [526.788215] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum hblank: 0
<7> [526.788308] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [526.788396] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 2, length 8
<7> [526.788483] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [526.788569] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [526.788652] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [526.788730] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [526.788806] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [526.788882] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [526.788959] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [526.789034] 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> [526.789116] 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> [526.789202] 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> [526.789278] 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> [526.789359] 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> [526.789458] 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> [526.789551] 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> [526.789645] 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> [526.789732] 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> [526.789809] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [526.789884] 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> [526.789958] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe src: 2880x1800+0+0
<7> [526.790034] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [526.790118] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [526.790210] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [526.790286] 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> [526.790363] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_hw_state: fracen: no,
<7> [526.790457] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] multiplier: 225, tx_clk_div: 1.
<7> [526.790552] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_rawhw_state:
<7> [526.790650] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] tx: 0x10, cmn: 0x21
<7> [526.790737] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1
<7> [526.790819] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [526.790893] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [526.790974] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [526.791053] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23
<7> [526.791128] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [526.791202] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [526.791277] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [526.791351] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [526.791450] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [526.791547] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [526.791643] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [526.791728] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [526.791801] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [526.791874] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [526.791952] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [526.792033] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [526.792108] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:273:plane 1C] fb: [FB:431] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [526.792183] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [526.792258] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [526.792331] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:303:plane 2C] fb: [NOFB], visible: no
<7> [526.792415] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:333:plane 3C] fb: [NOFB], visible: no
<7> [526.792496] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:363:plane 4C] fb: [NOFB], visible: no
<7> [526.792575] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:373:plane 5C] fb: [NOFB], visible: no
<7> [526.792656] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:383:cursor C] fb: [NOFB], visible: no
<7> [526.792941] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [526.793095] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_C
<7> [526.793238] 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> [526.793339] 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> [526.793459] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [526.793571] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=480
<7> [526.793708] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:392:DDI A/PHY A]
<7> [526.793786] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DDI TC1/PHY TC1]
<7> [526.793860] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST A]
<7> [526.793930] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:404:DP-MST B]
<7> [526.793998] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:405:DP-MST C]
<7> [526.794065] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DDI TC2/PHY TC2]
<7> [526.794132] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST A]
<7> [526.794198] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:416:DP-MST B]
<7> [526.794262] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:417:DP-MST C]
<7> [526.794326] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DDI TC3/PHY TC3]
<7> [526.794396] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST A]
<7> [526.794472] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:424:DP-MST B]
<7> [526.794559] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:425:DP-MST C]
<7> [526.794679] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xd
<7> [526.794846] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [526.794957] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [526.796275] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enable DPLL 0 (active 0x4, on? 0) for [CRTC:389:pipe C]
<7> [526.796377] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enabling DPLL 0
<7> [526.798366] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turn panel power on
<7> [526.798470] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 wait for panel power cycle (464 ms remaining)
<7> [526.799569] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [526.799734] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - long
<7> [526.799838] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [526.799952] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [527.267673] 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> [527.314538] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [527.314931] 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> [527.315264] 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> [527.351682] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [527.352131] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - long
<7> [527.352527] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [527.353229] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [527.527154] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [527.527662] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turning VDD on
<7> [527.528060] 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> [527.530326] 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> [527.531867] 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> [527.532693] 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> [527.533861] 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> [527.535267] 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> [527.536405] 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> [527.536867] 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> [527.538366] 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> [527.538776] 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> [527.539604] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe C
<7> [527.540152] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [527.540581] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe C
<7> [527.541046] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:393:eDP-1] set backlight PWM = 4518
<7> [527.557447] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] reserved 29491200 bytes of contiguous stolen space for FBC, limit: 1
<7> [527.557988] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] Enabling FBC on [PLANE:273:plane 1C]
<7> [527.573570] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR1
<7> [527.574961] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:393:eDP-1]
<7> [527.575406] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:389:pipe C]
<7> [530.132036] 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> [530.132555] 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> [531.971827] [IGT] kms_flip: finished subtest C-eDP1, FAIL
<6> [531.974669] [IGT] kms_flip: finished subtest flip-vs-expired-vblank-interruptible, FAIL
<7> [531.978640] xe 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:431] from all active usage due to RMFB ioctl
<7> [531.978701] xe 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:273:plane 1C] because [FB:431] is removed
<7> [531.978770] 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> [531.978907] 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> [531.979002] 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> [531.979089] 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> [531.979175] 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> [531.979295] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:389:pipe C] data rate 0 num active planes 0
<7> [531.979432] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 0 qgv_peak_bw: 38400
<7> [531.979567] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 0 qgv_peak_bw: 38400
<7> [531.979691] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [531.979816] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [531.979941] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 0
<7> [531.981017] xe 0000:00:02.0: [drm:intel_psr_disable_locked [xe]] Disabling PSR1
<7> [531.981162] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turning VDD on
<7> [531.981845] 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> [531.989469] xe 0000:00:02.0: [drm:__intel_fbc_disable [xe]] Disabling FBC on [PLANE:273:plane 1C]
<7> [532.010768] 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> [532.011188] 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> [532.011556] 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> [532.011880] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [532.012235] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:392:DDI A/PHY A] [CRTC:151:pipe A]
<7> [532.012584] 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> [532.012898] 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> [532.013219] 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> [532.013573] 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> [532.013904] 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> [532.014233] 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> [532.014578] 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> [532.014905] 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> [532.015228] 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> [532.015574] 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> [532.015903] 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> [532.016226] 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> [532.016570] 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> [532.016875] 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> [532.017177] 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> [532.017487] 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> [532.017770] 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> [532.018050] 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> [532.018330] 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> [532.018592] 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> [532.018840] 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> [532.019083] 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> [532.019333] 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> [532.019564] 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> [532.019795] 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> [532.020012] 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> [532.020229] 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> [532.020570] 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> [532.020776] 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> [532.020971] 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> [532.021164] 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> [532.021363] 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> [532.021549] 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> [532.021728] 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> [532.021907] 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> [532.022077] 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> [532.022242] 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> [532.022415] 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> [532.022576] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [532.022735] 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> [532.022888] 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> [532.023039] 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> [532.023188] 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> [532.023340] 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> [532.023482] 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> [532.023621] 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> [532.023756] 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> [532.023890] 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> [532.024020] 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> [532.024150] 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> [532.024277] 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> [532.024414] 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> [532.024536] 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> [532.024655] 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> [532.024770] 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> [532.024885] 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> [532.025000] 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> [532.025114] 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> [532.025225] 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> [532.025346] 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> [532.025458] 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> [532.025570] 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> [532.025681] 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> [532.025792] 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> [532.025903] 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> [532.026014] 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> [532.026125] 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> [532.026235] 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> [532.026356] 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> [532.026468] 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> [532.026579] 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> [532.026692] 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> [532.026804] 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> [532.026914] 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> [532.027025] 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> [532.027136] 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> [532.027247] 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> [532.027368] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:389:pipe C] fastset requirement not met, forcing full modeset
<7> [532.027486] xe 0000:00:02.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:389:pipe C] releasing DPLL 0
<7> [532.027724] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xd -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [532.027828] 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> [532.027910] 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> [532.027994] 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> [532.028074] 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> [532.028151] 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> [532.028227] 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> [532.028312] 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> [532.028392] 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> [532.028467] 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> [532.028573] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:151:pipe A] data rate 1390840 num active planes 1
<7> [532.028702] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [532.028822] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [532.028940] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [532.029056] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [532.029173] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [532.029304] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:151:pipe A] min cdclk: 0 kHz -> 173855 kHz
<7> [532.029428] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:389:pipe C] min cdclk: 173855 kHz -> 0 kHz
<7> [532.029619] xe 0000:00:02.0: [drm:intel_find_dpll [xe]] [CRTC:151:pipe A] allocated DPLL 0
<7> [532.029730] xe 0000:00:02.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:151:pipe A] reserving DPLL 0
<7> [532.029847] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:151:pipe A] enable: yes [modeset]
<7> [532.029972] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [532.030092] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: A, pipe bpp: 30, dithering: 0
<7> [532.030213] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [532.030352] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [532.030471] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [532.030587] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [532.030706] 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> [532.030828] 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> [532.030947] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [532.031064] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [532.031180] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled
<7> [532.031309] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum hblank: 0
<7> [532.031430] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [532.031547] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 4, length 14
<7> [532.031667] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [532.031785] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [532.031903] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [532.032021] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [532.032138] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [532.032257] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [532.032383] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [532.032501] 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> [532.032619] 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> [532.032736] 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> [532.032854] 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> [532.032972] 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> [532.033091] 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> [532.033210] 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> [532.033335] 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> [532.033454] 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> [532.033572] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [532.033691] 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> [532.033808] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe src: 2880x1800+0+0
<7> [532.033925] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [532.034042] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [532.034160] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [532.034278] 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> [532.034407] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_hw_state: fracen: no,
<7> [532.034524] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] multiplier: 225, tx_clk_div: 1.
<7> [532.034640] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_rawhw_state:
<7> [532.034758] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] tx: 0x10, cmn: 0x21
<7> [532.034877] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1
<7> [532.034994] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [532.035110] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [532.035227] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [532.035353] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23
<7> [532.035472] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [532.035587] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [532.035703] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [532.035820] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [532.035938] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [532.036054] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [532.036171] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [532.036297] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [532.036415] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [532.036532] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [532.036648] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [532.036764] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [532.036879] 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> [532.036997] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [532.037115] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [532.037232] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [532.037362] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [532.037479] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [532.037595] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [532.037713] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [532.037829] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:389:pipe C] enable: no [modeset]
<7> [532.037946] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:273:plane 1C] fb: [NOFB], visible: no
<7> [532.038061] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:303:plane 2C] fb: [NOFB], visible: no
<7> [532.038177] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:333:plane 3C] fb: [NOFB], visible: no
<7> [532.038303] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:363:plane 4C] fb: [NOFB], visible: no
<7> [532.038422] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:373:plane 5C] fb: [NOFB], visible: no
<7> [532.038538] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:383:cursor C] fb: [NOFB], visible: no
<7> [532.038839] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_A
<7> [532.039045] 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> [532.039301] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [532.039437] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [532.244601] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:393:eDP-1] set backlight PWM = 0
<7> [532.245855] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe C
<7> [532.257777] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turn panel power off
<7> [532.258258] 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> [532.258154] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [532.258739] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [532.258776] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - short
<7> [532.310010] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [532.310764] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [532.310847] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disable DPLL 0 (active 0x4, on? 1) for [CRTC:389:pipe C]
<7> [532.311151] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - short
<7> [532.310995] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [532.311910] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [532.312924] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disabling DPLL 0
<7> [532.313745] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [532.314585] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:392:DDI A/PHY A]
<7> [532.315143] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DDI TC1/PHY TC1]
<7> [532.315722] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST A]
<7> [532.316242] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:404:DP-MST B]
<7> [532.316832] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:405:DP-MST C]
<7> [532.317273] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DDI TC2/PHY TC2]
<7> [532.317746] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST A]
<7> [532.318184] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:416:DP-MST B]
<7> [532.318654] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:417:DP-MST C]
<7> [532.319090] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DDI TC3/PHY TC3]
<7> [532.319554] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST A]
<7> [532.319991] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:424:DP-MST B]
<7> [532.320455] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:425:DP-MST C]
<7> [532.320927] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [532.321557] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [532.321899] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 8 (mbus joined: yes)
<7> [532.322269] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [532.323465] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:151:pipe A]
<7> [532.323913] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enabling DPLL 0
<7> [532.326351] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turn panel power on
<7> [532.326662] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 wait for panel power cycle (485 ms remaining)
<7> [532.347832] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [532.348353] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - long
<7> [532.348882] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [532.349470] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [532.820756] 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> [532.893051] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [532.894257] 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> [532.895202] 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> [532.931105] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [532.931530] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:392:DDI A/PHY A] - long
<7> [532.931895] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [532.932625] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:392:DDI A/PHY A]
<7> [533.102687] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [533.103052] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:392:DDI A/PHY A] PPS 0 turning VDD on
<7> [533.103365] 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> [533.105574] 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> [533.107129] 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> [533.108094] 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> [533.109407] 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> [533.110956] 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> [533.112162] 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> [533.112576] 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> [533.114141] 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> [533.114582] 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> [533.115476] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe A
<7> [533.115990] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [533.116424] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe A
<7> [533.117026] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:393:eDP-1] set backlight PWM = 4518
<7> [533.149689] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR2
<7> [533.151345] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:393:eDP-1]
<7> [533.151768] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:151:pipe A]
<7> [533.154824] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_C
<7> [533.155345] 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> [533.155920] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [533.156426] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:389:pipe C]
<7> [533.157103] xe 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0
<6> [533.158864] [IGT] kms_flip: exiting, ret=98
<7> [533.174497] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [533.174669] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [533.174792] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
Created at 2026-05-14 10:15:56