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

Result: Fail

i915_display_info13 igt_runner13 results13.json results13-xe-load.json guc_logs13.tar i915_display_info_post_exec13 boot13 dmesg13

DetailValue
Duration 5.51 seconds
Hostname 
shard-lnl-1
Igt-Version 
IGT-Version: 2.2-gcfd86b6a8 (x86_64) (Linux: 6.18.0-lgci-xe-xe-4207-ae947e88cadd48481+ 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:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:923 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1889 run_test()
  #3 ../tests/kms_flip.c:2158 __igt_unique____real_main2045()
  #4 ../tests/kms_flip.c:2045 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest C-eDP1: FAIL (5.512s)
Subtest flip-vs-expired-vblank-interruptible: FAIL (27.973s)
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../lib/igt_kms.c:2386 kmstest_set_connector_dpms()
  #2 ../lib/igt_core.c:2961 igt_atexit_handler()
  #3 [erand48_r+0x1d6]
  #4 [exit+0x1e]
  #5 ../lib/igt_core.c:2416 igt_exit()
  #6 [main+0x3f]
  #7 [__libc_init_first+0x8a]
  #8 [__libc_start_main+0x8b]
  #9 [_start+0x25]
Err 
Starting dynamic subtest: C-eDP1
(kms_flip:7078) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:928:
(kms_flip:7078) CRITICAL: Failed assertion: end - start < 500
(kms_flip:7078) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest C-eDP1 failed.
**** DEBUG ****
(kms_flip:7078) DEBUG: Vblank took 68us
(kms_flip:7078) DEBUG: name = flip
last_ts = 421.902863
last_received_ts = 421.902039
last_seq = 152
current_ts = 421.919525
current_received_ts = 421.918701
current_seq = 153
count = 136
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 67us
(kms_flip:7078) DEBUG: name = flip
last_ts = 421.919525
last_received_ts = 421.918701
last_seq = 153
current_ts = 421.936188
current_received_ts = 421.935364
current_seq = 154
count = 137
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 69us
(kms_flip:7078) DEBUG: name = flip
last_ts = 421.936188
last_received_ts = 421.935364
last_seq = 154
current_ts = 421.952850
current_received_ts = 421.952057
current_seq = 155
count = 138
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 70us
(kms_flip:7078) DEBUG: name = flip
last_ts = 421.952850
last_received_ts = 421.952057
last_seq = 155
current_ts = 421.969482
current_received_ts = 421.968689
current_seq = 156
count = 139
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 68us
(kms_flip:7078) DEBUG: name = flip
last_ts = 421.969482
last_received_ts = 421.968689
last_seq = 156
current_ts = 421.986145
current_received_ts = 421.985352
current_seq = 157
count = 140
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 68us
(kms_flip:7078) DEBUG: name = flip
last_ts = 421.986145
last_received_ts = 421.985352
last_seq = 157
current_ts = 422.002808
current_received_ts = 422.002045
current_seq = 158
count = 141
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 69us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.002808
last_received_ts = 422.002045
last_seq = 158
current_ts = 422.019470
current_received_ts = 422.018677
current_seq = 159
count = 142
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 74us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.019470
last_received_ts = 422.018677
last_seq = 159
current_ts = 422.036133
current_received_ts = 422.035339
current_seq = 160
count = 143
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 70us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.036133
last_received_ts = 422.035339
last_seq = 160
current_ts = 422.052795
current_received_ts = 422.052002
current_seq = 161
count = 144
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 70us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.052795
last_received_ts = 422.052002
last_seq = 161
current_ts = 422.069458
current_received_ts = 422.068665
current_seq = 162
count = 145
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 68us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.069458
last_received_ts = 422.068665
last_seq = 162
current_ts = 422.086121
current_received_ts = 422.085297
current_seq = 163
count = 146
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 68us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.086121
last_received_ts = 422.085297
last_seq = 163
current_ts = 422.102783
current_received_ts = 422.101959
current_seq = 164
count = 147
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 106us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.102783
last_received_ts = 422.101959
last_seq = 164
current_ts = 422.119446
current_received_ts = 422.118622
current_seq = 165
count = 148
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 68us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.119446
last_received_ts = 422.118622
last_seq = 165
current_ts = 422.136108
current_received_ts = 422.135284
current_seq = 166
count = 149
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 68us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.136108
last_received_ts = 422.135284
last_seq = 166
current_ts = 422.152771
current_received_ts = 422.151978
current_seq = 167
count = 150
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 68us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.152771
last_received_ts = 422.151978
last_seq = 167
current_ts = 422.169403
current_received_ts = 422.168640
current_seq = 168
count = 151
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 87us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.169403
last_received_ts = 422.168640
last_seq = 168
current_ts = 422.186066
current_received_ts = 422.185272
current_seq = 169
count = 152
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 77us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.186066
last_received_ts = 422.185272
last_seq = 169
current_ts = 422.202728
current_received_ts = 422.201935
current_seq = 170
count = 153
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 85us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.202728
last_received_ts = 422.201935
last_seq = 170
current_ts = 422.219391
current_received_ts = 422.218597
current_seq = 171
count = 154
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 71us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.219391
last_received_ts = 422.218597
last_seq = 171
current_ts = 422.236053
current_received_ts = 422.235260
current_seq = 172
count = 155
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 69us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.236053
last_received_ts = 422.235260
last_seq = 172
current_ts = 422.252716
current_received_ts = 422.251923
current_seq = 173
count = 156
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 87us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.252716
last_received_ts = 422.251923
last_seq = 173
current_ts = 422.269379
current_received_ts = 422.268585
current_seq = 174
count = 157
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 70us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.269379
last_received_ts = 422.268585
last_seq = 174
current_ts = 422.286041
current_received_ts = 422.285217
current_seq = 175
count = 158
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 69us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.286041
last_received_ts = 422.285217
last_seq = 175
current_ts = 422.302704
current_received_ts = 422.301880
current_seq = 176
count = 159
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 69us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.302704
last_received_ts = 422.301880
last_seq = 176
current_ts = 422.319366
current_received_ts = 422.318542
current_seq = 177
count = 160
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 69us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.319366
last_received_ts = 422.318542
last_seq = 177
current_ts = 422.336029
current_received_ts = 422.335205
current_seq = 178
count = 161
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 69us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.336029
last_received_ts = 422.335205
last_seq = 178
current_ts = 422.352661
current_received_ts = 422.351868
current_seq = 179
count = 162
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 69us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.352661
last_received_ts = 422.351868
last_seq = 179
current_ts = 422.369354
current_received_ts = 422.368530
current_seq = 180
count = 163
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 68us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.369354
last_received_ts = 422.368530
last_seq = 180
current_ts = 422.385986
current_received_ts = 422.385193
current_seq = 181
count = 164
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 68us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.385986
last_received_ts = 422.385193
last_seq = 181
current_ts = 422.402679
current_received_ts = 422.401855
current_seq = 182
count = 165
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 68us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.402679
last_received_ts = 422.401855
last_seq = 182
current_ts = 422.419312
current_received_ts = 422.418518
current_seq = 183
count = 166
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 68us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.419312
last_received_ts = 422.418518
last_seq = 183
current_ts = 422.435974
current_received_ts = 422.435181
current_seq = 184
count = 167
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 69us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.435974
last_received_ts = 422.435181
last_seq = 184
current_ts = 422.452637
current_received_ts = 422.451843
current_seq = 185
count = 168
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 68us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.452637
last_received_ts = 422.451843
last_seq = 185
current_ts = 422.469299
current_received_ts = 422.468506
current_seq = 186
count = 169
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 69us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.469299
last_received_ts = 422.468506
last_seq = 186
current_ts = 422.485962
current_received_ts = 422.485138
current_seq = 187
count = 170
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 68us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.485962
last_received_ts = 422.485138
last_seq = 187
current_ts = 422.502625
current_received_ts = 422.501831
current_seq = 188
count = 171
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 68us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.502625
last_received_ts = 422.501831
last_seq = 188
current_ts = 422.519287
current_received_ts = 422.518463
current_seq = 189
count = 172
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 97us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.519287
last_received_ts = 422.518463
last_seq = 189
current_ts = 422.535950
current_received_ts = 422.535126
current_seq = 190
count = 173
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 95us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.535950
last_received_ts = 422.535126
last_seq = 190
current_ts = 422.552612
current_received_ts = 422.551788
current_seq = 191
count = 174
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 68us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.552612
last_received_ts = 422.551788
last_seq = 191
current_ts = 422.569275
current_received_ts = 422.568451
current_seq = 192
count = 175
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 70us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.569275
last_received_ts = 422.568451
last_seq = 192
current_ts = 422.585907
current_received_ts = 422.585114
current_seq = 193
count = 176
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 69us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.585907
last_received_ts = 422.585114
last_seq = 193
current_ts = 422.602600
current_received_ts = 422.601776
current_seq = 194
count = 177
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 68us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.602600
last_received_ts = 422.601776
last_seq = 194
current_ts = 422.619232
current_received_ts = 422.618439
current_seq = 195
count = 178
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 70us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.619232
last_received_ts = 422.618439
last_seq = 195
current_ts = 422.635895
current_received_ts = 422.635101
current_seq = 196
count = 179
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 70us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.635895
last_received_ts = 422.635101
last_seq = 196
current_ts = 422.652557
current_received_ts = 422.651764
current_seq = 197
count = 180
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 74us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.652557
last_received_ts = 422.651764
last_seq = 197
current_ts = 422.669220
current_received_ts = 422.668427
current_seq = 198
count = 181
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 49us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.669220
last_received_ts = 422.668427
last_seq = 198
current_ts = 422.685883
current_received_ts = 422.685089
current_seq = 199
count = 182
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 68us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.685883
last_received_ts = 422.685089
last_seq = 199
current_ts = 422.702545
current_received_ts = 422.701752
current_seq = 200
count = 183
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 69us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.702545
last_received_ts = 422.701752
last_seq = 200
current_ts = 422.719208
current_received_ts = 422.718384
current_seq = 201
count = 184
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 68us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.719208
last_received_ts = 422.718384
last_seq = 201
current_ts = 422.735870
current_received_ts = 422.735046
current_seq = 202
count = 185
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 68us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.735870
last_received_ts = 422.735046
last_seq = 202
current_ts = 422.752533
current_received_ts = 422.751709
current_seq = 203
count = 186
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 70us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.752533
last_received_ts = 422.751709
last_seq = 203
current_ts = 422.769196
current_received_ts = 422.768372
current_seq = 204
count = 187
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 69us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.769196
last_received_ts = 422.768372
last_seq = 204
current_ts = 422.785828
current_received_ts = 422.785065
current_seq = 205
count = 188
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 69us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.785828
last_received_ts = 422.785065
last_seq = 205
current_ts = 422.802490
current_received_ts = 422.801697
current_seq = 206
count = 189
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 69us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.802490
last_received_ts = 422.801697
last_seq = 206
current_ts = 422.819153
current_received_ts = 422.818359
current_seq = 207
count = 190
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 68us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.819153
last_received_ts = 422.818359
last_seq = 207
current_ts = 422.835815
current_received_ts = 422.835052
current_seq = 208
count = 191
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 70us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.835815
last_received_ts = 422.835052
last_seq = 208
current_ts = 422.852478
current_received_ts = 422.851685
current_seq = 209
count = 192
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 69us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.852478
last_received_ts = 422.851685
last_seq = 209
current_ts = 422.869141
current_received_ts = 422.868347
current_seq = 210
count = 193
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 69us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.869141
last_received_ts = 422.868347
last_seq = 210
current_ts = 422.885803
current_received_ts = 422.885010
current_seq = 211
count = 194
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 69us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.885803
last_received_ts = 422.885010
last_seq = 211
current_ts = 422.902466
current_received_ts = 422.901672
current_seq = 212
count = 195
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 69us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.902466
last_received_ts = 422.901672
last_seq = 212
current_ts = 422.919128
current_received_ts = 422.918304
current_seq = 213
count = 196
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 68us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.919128
last_received_ts = 422.918304
last_seq = 213
current_ts = 422.935791
current_received_ts = 422.934967
current_seq = 214
count = 197
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 107us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.935791
last_received_ts = 422.934967
last_seq = 214
current_ts = 422.952454
current_received_ts = 422.951630
current_seq = 215
count = 198
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 63us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.952454
last_received_ts = 422.951630
last_seq = 215
current_ts = 422.969116
current_received_ts = 422.968292
current_seq = 216
count = 199
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 68us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.969116
last_received_ts = 422.968292
last_seq = 216
current_ts = 422.985779
current_received_ts = 422.984955
current_seq = 217
count = 200
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 87us
(kms_flip:7078) DEBUG: name = flip
last_ts = 422.985779
last_received_ts = 422.984955
last_seq = 217
current_ts = 423.002411
current_received_ts = 423.001617
current_seq = 218
count = 201
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 68us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.002411
last_received_ts = 423.001617
last_seq = 218
current_ts = 423.019073
current_received_ts = 423.018280
current_seq = 219
count = 202
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 68us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.019073
last_received_ts = 423.018280
last_seq = 219
current_ts = 423.035736
current_received_ts = 423.034943
current_seq = 220
count = 203
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 87us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.035736
last_received_ts = 423.034943
last_seq = 220
current_ts = 423.052399
current_received_ts = 423.051605
current_seq = 221
count = 204
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 68us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.052399
last_received_ts = 423.051605
last_seq = 221
current_ts = 423.069061
current_received_ts = 423.068268
current_seq = 222
count = 205
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 68us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.069061
last_received_ts = 423.068268
last_seq = 222
current_ts = 423.085724
current_received_ts = 423.084900
current_seq = 223
count = 206
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 65us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.085724
last_received_ts = 423.084900
last_seq = 223
current_ts = 423.102386
current_received_ts = 423.101593
current_seq = 224
count = 207
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 48us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.102386
last_received_ts = 423.101593
last_seq = 224
current_ts = 423.119049
current_received_ts = 423.118225
current_seq = 225
count = 208
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 68us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.119049
last_received_ts = 423.118225
last_seq = 225
current_ts = 423.135712
current_received_ts = 423.134918
current_seq = 226
count = 209
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 69us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.135712
last_received_ts = 423.134918
last_seq = 226
current_ts = 423.152374
current_received_ts = 423.151550
current_seq = 227
count = 210
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 68us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.152374
last_received_ts = 423.151550
last_seq = 227
current_ts = 423.169037
current_received_ts = 423.168213
current_seq = 228
count = 211
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 69us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.169037
last_received_ts = 423.168213
last_seq = 228
current_ts = 423.185699
current_received_ts = 423.184875
current_seq = 229
count = 212
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 69us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.185699
last_received_ts = 423.184875
last_seq = 229
current_ts = 423.202362
current_received_ts = 423.201538
current_seq = 230
count = 213
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 69us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.202362
last_received_ts = 423.201538
last_seq = 230
current_ts = 423.218994
current_received_ts = 423.218201
current_seq = 231
count = 214
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 67us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.218994
last_received_ts = 423.218201
last_seq = 231
current_ts = 423.235687
current_received_ts = 423.234863
current_seq = 232
count = 215
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 69us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.235687
last_received_ts = 423.234863
last_seq = 232
current_ts = 423.252319
current_received_ts = 423.251526
current_seq = 233
count = 216
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 67us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.252319
last_received_ts = 423.251526
last_seq = 233
current_ts = 423.268982
current_received_ts = 423.268188
current_seq = 234
count = 217
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 104us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.268982
last_received_ts = 423.268188
last_seq = 234
current_ts = 423.285645
current_received_ts = 423.284851
current_seq = 235
count = 218
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 68us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.285645
last_received_ts = 423.284851
last_seq = 235
current_ts = 423.302307
current_received_ts = 423.301514
current_seq = 236
count = 219
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 68us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.302307
last_received_ts = 423.301514
last_seq = 236
current_ts = 423.318970
current_received_ts = 423.318146
current_seq = 237
count = 220
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 69us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.318970
last_received_ts = 423.318146
last_seq = 237
current_ts = 423.335632
current_received_ts = 423.334869
current_seq = 238
count = 221
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 125us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.335632
last_received_ts = 423.334869
last_seq = 238
current_ts = 423.352295
current_received_ts = 423.351501
current_seq = 239
count = 222
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 64us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.352295
last_received_ts = 423.351501
last_seq = 239
current_ts = 423.368958
current_received_ts = 423.368134
current_seq = 240
count = 223
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 58us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.368958
last_received_ts = 423.368134
last_seq = 240
current_ts = 423.385620
current_received_ts = 423.385101
current_seq = 241
count = 224
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 36us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.385620
last_received_ts = 423.385101
last_seq = 241
current_ts = 423.402283
current_received_ts = 423.401794
current_seq = 242
count = 225
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 32us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.402283
last_received_ts = 423.401794
last_seq = 242
current_ts = 423.418945
current_received_ts = 423.418488
current_seq = 243
count = 226
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 31us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.418945
last_received_ts = 423.418488
last_seq = 243
current_ts = 423.435577
current_received_ts = 423.434937
current_seq = 244
count = 227
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 28us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.435577
last_received_ts = 423.434937
last_seq = 244
current_ts = 423.452240
current_received_ts = 423.451782
current_seq = 245
count = 228
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 30us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.452240
last_received_ts = 423.451782
last_seq = 245
current_ts = 423.468903
current_received_ts = 423.468445
current_seq = 246
count = 229
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 87us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.468903
last_received_ts = 423.468445
last_seq = 246
current_ts = 423.485565
current_received_ts = 423.484894
current_seq = 247
count = 230
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 31us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.485565
last_received_ts = 423.484894
last_seq = 247
current_ts = 423.502228
current_received_ts = 423.501648
current_seq = 248
count = 231
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 31us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.502228
last_received_ts = 423.501648
last_seq = 248
current_ts = 423.518890
current_received_ts = 423.518097
current_seq = 249
count = 232
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 47us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.518890
last_received_ts = 423.518097
last_seq = 249
current_ts = 423.535553
current_received_ts = 423.534790
current_seq = 250
count = 233
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 50us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.535553
last_received_ts = 423.534790
last_seq = 250
current_ts = 423.552216
current_received_ts = 423.551422
current_seq = 251
count = 234
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 50us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.552216
last_received_ts = 423.551422
last_seq = 251
current_ts = 423.568878
current_received_ts = 423.568085
current_seq = 252
count = 235
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 58us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.568878
last_received_ts = 423.568085
last_seq = 252
current_ts = 423.585541
current_received_ts = 423.584747
current_seq = 253
count = 236
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 55us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.585541
last_received_ts = 423.584747
last_seq = 253
current_ts = 423.602203
current_received_ts = 423.601379
current_seq = 254
count = 237
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 43us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.602203
last_received_ts = 423.601379
last_seq = 254
current_ts = 423.618866
current_received_ts = 423.618073
current_seq = 255
count = 238
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 41us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.618866
last_received_ts = 423.618073
last_seq = 255
current_ts = 423.635498
current_received_ts = 423.634735
current_seq = 256
count = 239
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 46us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.635498
last_received_ts = 423.634735
last_seq = 256
current_ts = 423.652161
current_received_ts = 423.651367
current_seq = 257
count = 240
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 44us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.652161
last_received_ts = 423.651367
last_seq = 257
current_ts = 423.668823
current_received_ts = 423.668060
current_seq = 258
count = 241
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 42us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.668823
last_received_ts = 423.668060
last_seq = 258
current_ts = 423.685486
current_received_ts = 423.684692
current_seq = 259
count = 242
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 49us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.685486
last_received_ts = 423.684692
last_seq = 259
current_ts = 423.702148
current_received_ts = 423.701385
current_seq = 260
count = 243
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 93us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.702148
last_received_ts = 423.701385
last_seq = 260
current_ts = 423.718811
current_received_ts = 423.718048
current_seq = 261
count = 244
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 117us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.718811
last_received_ts = 423.718048
last_seq = 261
current_ts = 423.735474
current_received_ts = 423.734711
current_seq = 262
count = 245
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 101us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.735474
last_received_ts = 423.734711
last_seq = 262
current_ts = 423.752136
current_received_ts = 423.751343
current_seq = 263
count = 246
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 46us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.752136
last_received_ts = 423.751343
last_seq = 263
current_ts = 423.768799
current_received_ts = 423.767975
current_seq = 264
count = 247
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 63us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.768799
last_received_ts = 423.767975
last_seq = 264
current_ts = 423.785461
current_received_ts = 423.784668
current_seq = 265
count = 248
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 62us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.785461
last_received_ts = 423.784668
last_seq = 265
current_ts = 423.802124
current_received_ts = 423.801300
current_seq = 266
count = 249
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 45us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.802124
last_received_ts = 423.801300
last_seq = 266
current_ts = 423.818787
current_received_ts = 423.817993
current_seq = 267
count = 250
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 36us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.818787
last_received_ts = 423.817993
last_seq = 267
current_ts = 423.835419
current_received_ts = 423.834656
current_seq = 268
count = 251
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 54us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.835419
last_received_ts = 423.834656
last_seq = 268
current_ts = 423.852081
current_received_ts = 423.851288
current_seq = 269
count = 252
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 49us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.852081
last_received_ts = 423.851288
last_seq = 269
current_ts = 423.868744
current_received_ts = 423.867981
current_seq = 270
count = 253
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 74us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.868744
last_received_ts = 423.867981
last_seq = 270
current_ts = 423.885406
current_received_ts = 423.884644
current_seq = 271
count = 254
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 45us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.885406
last_received_ts = 423.884644
last_seq = 271
current_ts = 423.902069
current_received_ts = 423.901276
current_seq = 272
count = 255
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 57us
(kms_flip:7078) DEBUG: name = flip
last_ts = 423.902069
last_received_ts = 423.901276
last_seq = 272
current_ts = 423.918732
current_received_ts = 423.917938
current_seq = 273
count = 256
seq_step = 1
(kms_flip:7078) DEBUG: Vblank took 624us
(kms_flip:7078) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:928:
(kms_flip:7078) CRITICAL: Failed assertion: end - start < 500
(kms_flip:7078) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:7078) igt_core-INFO: Stack trace:
(kms_flip:7078) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:7078) igt_core-INFO:   #1 ../tests/kms_flip.c:923 run_test_on_crtc_set.constprop.0()
(kms_flip:7078) igt_core-INFO:   #2 ../tests/kms_flip.c:1889 run_test()
(kms_flip:7078) igt_core-INFO:   #3 ../tests/kms_flip.c:2158 __igt_unique____real_main2045()
(kms_flip:7078) igt_core-INFO:   #4 ../tests/kms_flip.c:2045 main()
(kms_flip:7078) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:7078) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:7078) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest C-eDP1: FAIL (5.512s)
Subtest flip-vs-expired-vblank-interruptible: FAIL (27.973s)
(kms_flip:7078) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2386:
(kms_flip:7078) igt_kms-CRITICAL: Failed assertion: found_it
(kms_flip:7078) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor
(kms_flip:7078) igt_kms-CRITICAL: DPMS property not found on 391
Dmesg
<6> [449.078430] [IGT] kms_flip: starting dynamic subtest C-eDP1
<7> [449.086547] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:429]
<7> [449.087925] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:430]
<7> [449.093562] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [449.093884] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [449.094113] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [449.147611] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:149:pipe A]
<7> [449.147734] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [449.147915] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [449.148180] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:268:pipe B]
<7> [449.148253] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [449.148399] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [449.148523] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [449.148664] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [449.148787] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268: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> [449.148904] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [449.149016] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [449.149126] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [449.149232] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [449.149337] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [449.149444] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [449.149553] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [449.149669] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [449.149779] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [449.149881] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1800, found 0)
<7> [449.149973] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [449.150063] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [449.150150] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1906, found 0)
<7> [449.150236] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1906, found 0)
<7> [449.150322] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [449.150405] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [449.150488] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [449.150609] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [449.150723] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [449.150838] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [449.150927] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [449.151011] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1800, found 0)
<7> [449.151094] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [449.151171] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [449.151247] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1906, found 0)
<7> [449.151323] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1906, found 0)
<7> [449.151398] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [449.151475] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [449.151558] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [449.151656] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [449.151754] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 347710, found 0)
<7> [449.151851] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 347710, found 0)
<7> [449.151934] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in port_clock (expected 432000, found 0)
<7> [449.152021] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:268:pipe B] fastset requirement not met, forcing full modeset
<7> [449.152108] xe 0000:00:02.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:268:pipe B] releasing DPLL 0
<7> [449.152214] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [449.152285] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:268:pipe B] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [449.152376] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:262:cursor B] ddb (4051 - 4096) -> ( 0 - 0), size 45 -> 0
<7> [449.152451] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:268:pipe B] min cdclk: 173855 kHz -> 0 kHz
<7> [449.152544] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [449.152650] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 153600 kHz, actual 153600 kHz
<7> [449.152759] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [449.152882] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:268:pipe B] enable: no [modeset]
<7> [449.152971] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:152:plane 1B] fb: [NOFB], visible: no
<7> [449.153062] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:182:plane 2B] fb: [NOFB], visible: no
<7> [449.153152] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:212:plane 3B] fb: [NOFB], visible: no
<7> [449.153235] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:242:plane 4B] fb: [NOFB], visible: no
<7> [449.153314] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:252:plane 5B] fb: [NOFB], visible: no
<7> [449.153391] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:262:cursor B] fb: [NOFB], visible: no
<7> [449.153606] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [449.153715] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [449.359858] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:391:eDP-1] set backlight PWM = 0
<7> [449.360334] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe B
<7> [449.380515] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turn panel power off
<7> [449.381111] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [449.381638] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [449.432848] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [449.433181] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disable DPLL 0 (active 0x2, on? 1) for [CRTC:268:pipe B]
<7> [449.433800] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [449.434174] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - short
<7> [449.434402] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disabling DPLL 0
<7> [449.434535] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [449.434763] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [449.435224] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:390:DDI A/PHY A]
<7> [449.435515] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:399:DDI TC1/PHY TC1]
<7> [449.435827] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DP-MST A]
<7> [449.436080] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:402:DP-MST B]
<7> [449.436314] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST C]
<7> [449.436547] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:411:DDI TC2/PHY TC2]
<7> [449.436810] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DP-MST A]
<7> [449.437054] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:414:DP-MST B]
<7> [449.437340] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST C]
<7> [449.437568] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:419:DDI TC3/PHY TC3]
<7> [449.437862] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DP-MST A]
<7> [449.438109] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:422:DP-MST B]
<7> [449.438352] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST C]
<7> [449.438591] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:391:eDP-1]
<7> [449.439018] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [449.439281] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [449.439540] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [449.440049] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_B
<7> [449.440538] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [449.441036] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:268:pipe B]
<7> [449.441395] 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> [449.441865] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [449.442211] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=384
<7> [449.443132] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]
<7> [449.443540] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]
<7> [449.443630] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:391:eDP-1]
<7> [449.443815] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CONNECTOR:391:eDP-1] Limiting display bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [449.444265] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:390:DDI A/PHY A][CRTC:387:pipe C] DP link limits: pixel clock 347710 kHz DSC off max lanes 4 max rate 810000 max pipe_bpp 30 max link_bpp 30.0000
<7> [449.444668] xe 0000:00:02.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 4 clock 432000 bpp input 30 compressed 0.0000 link rate required 1303913 available 1728000
<7> [449.445043] xe 0000:00:02.0: [drm:intel_psr_compute_config [xe]] PSR2 not supported in transcoder C
<7> [449.445406] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:387:pipe C] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [449.445806] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:390:DDI A/PHY A] [CRTC:387:pipe C]
<7> [449.446159] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.enable (expected no, found yes)
<7> [449.446477] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.active (expected no, found yes)
<7> [449.446818] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2)
<7> [449.447185] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in lane_count (expected 0, found 4)
<7> [449.447486] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387: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> [449.447794] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [449.448126] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [449.448381] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [449.448646] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [449.448892] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)
<7> [449.449125] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)
<7> [449.449350] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)
<7> [449.449562] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)
<7> [449.449832] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [449.450032] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1800)
<7> [449.450239] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)
<7> [449.450464] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)
<7> [449.450667] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1906)
<7> [449.450845] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1906)
<7> [449.451048] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [449.451234] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [449.451403] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)
<7> [449.451565] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)
<7> [449.451738] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)
<7> [449.451887] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)
<7> [449.452057] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [449.452220] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1800)
<7> [449.452357] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)
<7> [449.452516] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)
<7> [449.452693] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1906)
<7> [449.452854] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1906)
<7> [449.452996] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [449.453124] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [449.453247] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [449.453368] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [449.453484] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 347710)
<7> [449.453604] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 347710)
<7> [449.453751] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in port_clock (expected 0, found 432000)
<7> [449.453868] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:387:pipe C] fastset requirement not met, forcing full modeset
<7> [449.454008] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xd (total dbuf slices 0xf), mbus joined? no->no
<7> [449.454114] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:387:pipe C] dbuf slices 0x0 -> 0xc, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x4
<7> [449.454244] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:271:plane 1C] ddb ( 0 - 0) -> ( 0 - 2003), size 0 -> 2003
<7> [449.454340] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:381:cursor C] ddb ( 0 - 0) -> (2003 - 2048), size 0 -> 45
<7> [449.454425] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:271: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> [449.454511] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:271: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> [449.454602] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:271: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> [449.454713] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:271: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> [449.454822] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:387:pipe C] data rate 1390840 num active planes 1
<7> [449.454950] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [449.455060] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [449.455163] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [449.455264] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [449.455360] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [449.455474] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:387:pipe C] min cdclk: 0 kHz -> 173855 kHz
<7> [449.455612] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [449.455735] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [449.455843] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [449.455962] xe 0000:00:02.0: [drm:intel_find_dpll [xe]] [CRTC:387:pipe C] allocated DPLL 0
<7> [449.456061] xe 0000:00:02.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:387:pipe C] reserving DPLL 0
<7> [449.456159] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:387:pipe C] enable: yes [modeset]
<7> [449.456260] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [449.456352] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: C, pipe bpp: 30, dithering: 0
<7> [449.456445] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [449.456535] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [449.456620] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [449.456719] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [449.456819] 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> [449.456918] 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> [449.456994] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [449.457070] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [449.457146] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: disabled, panel replay: disabled, selective fetch: disabled
<7> [449.457228] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0
<7> [449.457310] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [449.457387] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 2, length 8
<7> [449.457465] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [449.457547] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [449.457670] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [449.457770] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [449.457866] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [449.457951] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [449.458024] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [449.458097] 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> [449.458173] 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> [449.458265] 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> [449.458353] 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> [449.458425] 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> [449.458500] 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> [449.458577] 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> [449.458669] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 432000, pipe src: 2880x1800+0+0, pixel rate 347710, min cdclk 173855
<7> [449.458771] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [449.458869] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [449.458958] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [449.459035] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [449.459116] 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> [449.459196] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_hw_state: clock: 432000, fracen: no,
<7> [449.459272] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] multiplier: 225, tx_clk_div: 1.
<7> [449.459346] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_rawhw_state:
<7> [449.459424] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] tx: 0x10, cmn: 0x21
<7> [449.459504] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1
<7> [449.459584] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [449.459683] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [449.459786] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [449.459880] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23
<7> [449.459960] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [449.460045] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [449.460124] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [449.460201] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [449.460281] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [449.460359] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [449.460436] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [449.460507] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [449.460579] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [449.460679] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [449.460689] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [449.460782] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [449.460848] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [449.460896] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [449.460982] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [449.461006] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [449.461096] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:271:plane 1C] fb: [FB:429] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [449.461128] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling always-on
<7> [449.461180] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [449.461262] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [449.461344] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:301:plane 2C] fb: [NOFB], visible: no
<7> [449.461422] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:331:plane 3C] fb: [NOFB], visible: no
<7> [449.461495] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:361:plane 4C] fb: [NOFB], visible: no
<7> [449.461567] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:371:plane 5C] fb: [NOFB], visible: no
<7> [449.461653] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:381:cursor C] fb: [NOFB], visible: no
<7> [449.462086] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling always-on
<7> [449.462201] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [449.462447] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [449.462590] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [449.462753] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_C
<7> [449.462948] 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> [449.463053] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [449.463142] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=480
<7> [449.463227] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:390:DDI A/PHY A]
<7> [449.463297] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:399:DDI TC1/PHY TC1]
<7> [449.463365] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DP-MST A]
<7> [449.463431] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:402:DP-MST B]
<7> [449.463497] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST C]
<7> [449.463571] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:411:DDI TC2/PHY TC2]
<7> [449.463670] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DP-MST A]
<7> [449.463759] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:414:DP-MST B]
<7> [449.463843] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST C]
<7> [449.463928] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:419:DDI TC3/PHY TC3]
<7> [449.463999] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DP-MST A]
<7> [449.464066] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:422:DP-MST B]
<7> [449.464129] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST C]
<7> [449.464213] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xd
<7> [449.464377] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [449.464506] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [449.465652] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enable DPLL 0 (active 0x4, on? 0) for [CRTC:387:pipe C]
<7> [449.465785] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enabling DPLL 0
<7> [449.467507] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turn panel power on
<7> [449.467594] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 wait for panel power cycle (466 ms remaining)
<7> [449.471224] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [449.471369] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - long
<7> [449.471460] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [449.471585] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [449.935912] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [449.992379] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [449.992846] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 wait for panel power on
<7> [449.993242] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [450.029422] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [450.030159] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - long
<7> [450.030743] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [450.031308] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [450.202820] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [450.203153] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turning VDD on
<7> [450.203442] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [450.205652] 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> [450.206922] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [450.207617] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 05
<7> [450.208635] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [450.209679] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [450.210663] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Clock recovery OK
<7> [450.210929] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Using DP training pattern TPS4
<7> [450.212278] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [450.212536] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Link Training passed at link rate = 432000, lane count = 4
<7> [450.213274] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe C
<7> [450.213623] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [450.213894] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe C
<7> [450.214234] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:391:eDP-1] set backlight PWM = 4518
<7> [450.215198] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] reserved 14745600 bytes of contiguous stolen space for FBC, limit: 2
<7> [450.215457] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] Enabling FBC on [PLANE:271:plane 1C]
<7> [450.230404] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR1
<7> [450.231637] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:391:eDP-1]
<7> [450.231934] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:387:pipe C]
<7> [452.760933] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turning VDD off
<7> [452.761345] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x00000067
<6> [454.591549] [IGT] kms_flip: finished subtest C-eDP1, FAIL
<6> [454.595290] [IGT] kms_flip: finished subtest flip-vs-expired-vblank-interruptible, FAIL
<7> [454.606585] xe 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:429] from all active usage due to RMFB ioctl
<7> [454.606713] xe 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:271:plane 1C] because [FB:429] is removed
<7> [454.606889] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:271:plane 1C] ddb ( 0 - 2003) -> ( 0 - 0), size 2003 -> 0
<7> [454.607287] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:271: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> [454.607635] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:271: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> [454.608000] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:271: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> [454.608302] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:271: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> [454.608684] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:387:pipe C] data rate 0 num active planes 0
<7> [454.609266] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 0 qgv_peak_bw: 38400
<7> [454.609661] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 0 qgv_peak_bw: 38400
<7> [454.610081] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [454.610465] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [454.610860] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 0
<7> [454.613874] xe 0000:00:02.0: [drm:intel_psr_disable_locked [xe]] Disabling PSR1
<7> [454.614402] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turning VDD on
<7> [454.614832] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006f
<7> [454.629660] xe 0000:00:02.0: [drm:__intel_fbc_disable [xe]] Disabling FBC on [PLANE:271:plane 1C]
<7> [454.650521] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CONNECTOR:391:eDP-1] Limiting display bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [454.651070] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:390:DDI A/PHY A][CRTC:149:pipe A] DP link limits: pixel clock 347710 kHz DSC off max lanes 4 max rate 810000 max pipe_bpp 30 max link_bpp 30.0000
<7> [454.651524] xe 0000:00:02.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 4 clock 432000 bpp input 30 compressed 0.0000 link rate required 1303913 available 1728000
<7> [454.652013] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:149:pipe A] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [454.652499] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:390:DDI A/PHY A] [CRTC:149:pipe A]
<7> [454.653029] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [454.653471] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [454.653880] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [454.654268] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [454.654637] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6329869/8388608 link 421991/524288)
<7> [454.655044] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [454.655412] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [454.655777] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [454.656210] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [454.656610] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)
<7> [454.657030] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)
<7> [454.657439] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)
<7> [454.657827] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)
<7> [454.658228] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [454.658570] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1800)
<7> [454.658934] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)
<7> [454.659246] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)
<7> [454.659595] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1906)
<7> [454.659890] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1906)
<7> [454.660176] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [454.660442] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [454.660701] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)
<7> [454.660970] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)
<7> [454.661213] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)
<7> [454.661443] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)
<7> [454.661699] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [454.661943] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1800)
<7> [454.662162] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)
<7> [454.662367] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)
<7> [454.662566] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1906)
<7> [454.662796] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1906)
<7> [454.663030] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [454.663245] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [454.663434] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [454.663616] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [454.663828] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 347710)
<7> [454.664018] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 347710)
<7> [454.664198] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in port_clock (expected 0, found 432000)
<7> [454.664360] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:149:pipe A] fastset requirement not met, forcing full modeset
<7> [454.664518] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.enable (expected yes, found no)
<7> [454.664693] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.active (expected yes, found no)
<7> [454.664939] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in cpu_transcoder (expected 2, found -1)
<7> [454.665083] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in lane_count (expected 4, found 0)
<7> [454.665223] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387: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> [454.665360] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [454.665509] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [454.665661] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [454.665795] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [454.665939] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [454.666065] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [454.666187] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [454.666302] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [454.666417] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [454.666534] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1800, found 0)
<7> [454.666650] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [454.666764] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [454.666885] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1906, found 0)
<7> [454.666996] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1906, found 0)
<7> [454.667105] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [454.667208] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [454.667308] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [454.667406] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [454.667501] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [454.667595] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [454.667688] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [454.667777] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1800, found 0)
<7> [454.667885] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [454.667982] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [454.668079] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1906, found 0)
<7> [454.668165] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1906, found 0)
<7> [454.668248] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [454.668329] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [454.668409] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [454.668486] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [454.668566] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 347710, found 0)
<7> [454.668646] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 347710, found 0)
<7> [454.668725] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in port_clock (expected 432000, found 0)
<7> [454.668802] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:387:pipe C] fastset requirement not met, forcing full modeset
<7> [454.668915] xe 0000:00:02.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:387:pipe C] releasing DPLL 0
<7> [454.669076] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xd -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [454.669164] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:149:pipe A] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x4 -> 0x1
<7> [454.669233] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:387:pipe C] dbuf slices 0xc -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x4 -> 0x1
<7> [454.669299] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:33:plane 1A] ddb ( 0 - 0) -> ( 0 - 4051), size 0 -> 4051
<7> [454.669359] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:143:cursor A] ddb ( 0 - 0) -> (4051 - 4096), size 0 -> 45
<7> [454.669417] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [454.669475] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33: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> [454.669534] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33: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> [454.669591] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33: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> [454.669651] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:381:cursor C] ddb (2003 - 2048) -> ( 0 - 0), size 45 -> 0
<7> [454.669729] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:149:pipe A] data rate 1390840 num active planes 1
<7> [454.669822] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [454.669926] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [454.670016] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [454.670107] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [454.670198] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [454.670279] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:149:pipe A] min cdclk: 0 kHz -> 173855 kHz
<7> [454.670360] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:387:pipe C] min cdclk: 173855 kHz -> 0 kHz
<7> [454.670478] xe 0000:00:02.0: [drm:intel_find_dpll [xe]] [CRTC:149:pipe A] allocated DPLL 0
<7> [454.670557] xe 0000:00:02.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:149:pipe A] reserving DPLL 0
<7> [454.670641] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:149:pipe A] enable: yes [modeset]
<7> [454.670725] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [454.670806] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: A, pipe bpp: 30, dithering: 0
<7> [454.670923] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [454.671030] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [454.671131] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [454.671211] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [454.671298] 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> [454.671380] 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> [454.671461] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [454.671542] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [454.671621] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled
<7> [454.671699] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0
<7> [454.671776] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [454.671874] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 4, length 14
<7> [454.671976] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [454.672077] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [454.672164] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [454.672240] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [454.672314] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [454.672389] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [454.672462] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [454.672534] 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> [454.672607] 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> [454.672681] 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> [454.672755] 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> [454.672833] 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> [454.672927] 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> [454.673022] 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> [454.673116] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 432000, pipe src: 2880x1800+0+0, pixel rate 347710, min cdclk 173855
<7> [454.673197] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [454.673270] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [454.673341] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [454.673417] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [454.673494] 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> [454.673571] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_hw_state: clock: 432000, fracen: no,
<7> [454.673648] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] multiplier: 225, tx_clk_div: 1.
<7> [454.673722] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_rawhw_state:
<7> [454.673797] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] tx: 0x10, cmn: 0x21
<7> [454.673907] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1
<7> [454.674006] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [454.674102] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [454.674184] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [454.674263] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23
<7> [454.674336] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [454.674409] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [454.674482] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [454.674554] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [454.674626] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [454.674698] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [454.674769] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [454.674854] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [454.674950] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [454.675043] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [454.675136] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [454.675217] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [454.675293] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [454.675366] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:33:plane 1A] fb: [FB:392] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [454.675441] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [454.675515] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [454.675591] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:63:plane 2A] fb: [NOFB], visible: no
<7> [454.675664] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:93:plane 3A] fb: [NOFB], visible: no
<7> [454.675738] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:123:plane 4A] fb: [NOFB], visible: no
<7> [454.675817] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:133:plane 5A] fb: [NOFB], visible: no
<7> [454.675909] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:143:cursor A] fb: [NOFB], visible: no
<7> [454.676005] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:387:pipe C] enable: no [modeset]
<7> [454.676100] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:271:plane 1C] fb: [NOFB], visible: no
<7> [454.676176] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:301:plane 2C] fb: [NOFB], visible: no
<7> [454.676250] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:331:plane 3C] fb: [NOFB], visible: no
<7> [454.676321] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:361:plane 4C] fb: [NOFB], visible: no
<7> [454.676392] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:371:plane 5C] fb: [NOFB], visible: no
<7> [454.676463] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:381:cursor C] fb: [NOFB], visible: no
<7> [454.676592] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_A
<7> [454.677234] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [454.677372] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [454.881089] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:391:eDP-1] set backlight PWM = 0
<7> [454.881804] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe C
<7> [454.902857] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turn panel power off
<7> [454.903250] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [454.903401] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [454.903836] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [454.903816] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - short
<7> [454.956284] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [454.957027] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - short
<7> [454.963996] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [454.964488] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disable DPLL 0 (active 0x4, on? 1) for [CRTC:387:pipe C]
<7> [454.964524] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [454.965008] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [454.965829] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disabling DPLL 0
<7> [454.966336] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [454.966810] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:390:DDI A/PHY A]
<7> [454.967198] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:399:DDI TC1/PHY TC1]
<7> [454.967487] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DP-MST A]
<7> [454.967763] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:402:DP-MST B]
<7> [454.968064] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST C]
<7> [454.968397] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:411:DDI TC2/PHY TC2]
<7> [454.968659] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DP-MST A]
<7> [454.968996] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:414:DP-MST B]
<7> [454.969265] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST C]
<7> [454.969518] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:419:DDI TC3/PHY TC3]
<7> [454.969765] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DP-MST A]
<7> [454.970049] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:422:DP-MST B]
<7> [454.970340] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST C]
<7> [454.970663] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [454.971145] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [454.971422] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 8 (mbus joined: yes)
<7> [454.971751] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [454.973030] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:149:pipe A]
<7> [454.973437] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enabling DPLL 0
<7> [454.975531] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turn panel power on
<7> [454.975796] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 wait for panel power cycle (489 ms remaining)
<7> [454.993456] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [454.994030] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - long
<7> [454.994468] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [454.994917] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [455.473321] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [455.575230] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [455.575568] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 wait for panel power on
<7> [455.575864] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [455.612137] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [455.612865] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - long
<7> [455.613447] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [455.614218] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [455.787420] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [455.787906] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turning VDD on
<7> [455.788379] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [455.790662] 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> [455.791988] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [455.792912] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 05
<7> [455.794193] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [455.795395] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [455.796451] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Clock recovery OK
<7> [455.796796] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Using DP training pattern TPS4
<7> [455.798332] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [455.798703] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Link Training passed at link rate = 432000, lane count = 4
<7> [455.799594] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe A
<7> [455.800161] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [455.800585] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe A
<7> [455.801017] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:391:eDP-1] set backlight PWM = 4518
<7> [455.817037] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR2
<7> [455.818833] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:391:eDP-1]
<7> [455.819252] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:149:pipe A]
<7> [455.821832] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_C
<7> [455.822786] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [455.823226] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:387:pipe C]
<7> [455.824168] xe 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0
<6> [455.825141] [IGT] kms_flip: exiting, ret=98
Created at 2025-12-09 06:46:48