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

Result: Fail

i915_display_info2 igt_runner2 results2.json results2-xe-load.json guc_logs2.tar i915_display_info_post_exec2 boot2 dmesg2

DetailValue
Duration 4.98 seconds
Hostname 
shard-bmg-3
Igt-Version 
IGT-Version: 2.3-ge90a75275 (x86_64) (Linux: 7.0.0-rc4-lgci-xe-xe-4752-7535044a2418d22b5-debug+ x86_64)
Out 
Starting dynamic subtest: D-HDMI-A3
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9 
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:925 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1891 run_test()
  #3 ../tests/kms_flip.c:2162 __igt_unique____real_main2047()
  #4 ../tests/kms_flip.c:2047 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest D-HDMI-A3: FAIL (4.982s)
Subtest flip-vs-expired-vblank-interruptible: FAIL (28.143s)
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../lib/igt_kms.c:2352 kmstest_set_connector_dpms()
  #2 ../lib/igt_core.c:2961 igt_atexit_handler()
  #3 [erand48_r+0x1d6]
  #4 [exit+0x1e]
  #5 ../lib/igt_core.c:2416 igt_exit()
  #6 [main+0x3f]
  #7 [__libc_init_first+0x8a]
  #8 [__libc_start_main+0x8b]
  #9 [_start+0x25]
Err 
Starting dynamic subtest: D-HDMI-A3
(kms_flip:6084) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:930:
(kms_flip:6084) CRITICAL: Failed assertion: end - start < 500
(kms_flip:6084) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest D-HDMI-A3 failed.
**** DEBUG ****
(kms_flip:6084) DEBUG: Vblank took 26us
(kms_flip:6084) DEBUG: name = flip
last_ts = 215.630402
last_received_ts = 215.629898
last_seq = 391
current_ts = 215.647064
current_received_ts = 215.646606
current_seq = 392
count = 128
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 25us
(kms_flip:6084) DEBUG: name = flip
last_ts = 215.647064
last_received_ts = 215.646606
last_seq = 392
current_ts = 215.663727
current_received_ts = 215.663361
current_seq = 393
count = 129
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 25us
(kms_flip:6084) DEBUG: name = flip
last_ts = 215.663727
last_received_ts = 215.663361
last_seq = 393
current_ts = 215.680405
current_received_ts = 215.679901
current_seq = 394
count = 130
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 25us
(kms_flip:6084) DEBUG: name = flip
last_ts = 215.680405
last_received_ts = 215.679901
last_seq = 394
current_ts = 215.697083
current_received_ts = 215.696686
current_seq = 395
count = 131
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 25us
(kms_flip:6084) DEBUG: name = flip
last_ts = 215.697083
last_received_ts = 215.696686
last_seq = 395
current_ts = 215.713730
current_received_ts = 215.713364
current_seq = 396
count = 132
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 26us
(kms_flip:6084) DEBUG: name = flip
last_ts = 215.713730
last_received_ts = 215.713364
last_seq = 396
current_ts = 215.730408
current_received_ts = 215.729904
current_seq = 397
count = 133
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 26us
(kms_flip:6084) DEBUG: name = flip
last_ts = 215.730408
last_received_ts = 215.729904
last_seq = 397
current_ts = 215.747070
current_received_ts = 215.746689
current_seq = 398
count = 134
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 25us
(kms_flip:6084) DEBUG: name = flip
last_ts = 215.747070
last_received_ts = 215.746689
last_seq = 398
current_ts = 215.763733
current_received_ts = 215.763351
current_seq = 399
count = 135
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 241us
(kms_flip:6084) DEBUG: name = flip
last_ts = 215.763733
last_received_ts = 215.763351
last_seq = 399
current_ts = 215.780411
current_received_ts = 215.780045
current_seq = 400
count = 136
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 25us
(kms_flip:6084) DEBUG: name = flip
last_ts = 215.780411
last_received_ts = 215.780045
last_seq = 400
current_ts = 215.797073
current_received_ts = 215.796692
current_seq = 401
count = 137
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 27us
(kms_flip:6084) DEBUG: name = flip
last_ts = 215.797073
last_received_ts = 215.796692
last_seq = 401
current_ts = 215.813736
current_received_ts = 215.813370
current_seq = 402
count = 138
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 240us
(kms_flip:6084) DEBUG: name = flip
last_ts = 215.813736
last_received_ts = 215.813370
last_seq = 402
current_ts = 215.830399
current_received_ts = 215.830048
current_seq = 403
count = 139
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 232us
(kms_flip:6084) DEBUG: name = flip
last_ts = 215.830399
last_received_ts = 215.830048
last_seq = 403
current_ts = 215.847076
current_received_ts = 215.846695
current_seq = 404
count = 140
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 243us
(kms_flip:6084) DEBUG: name = flip
last_ts = 215.847076
last_received_ts = 215.846695
last_seq = 404
current_ts = 215.863739
current_received_ts = 215.863373
current_seq = 405
count = 141
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 240us
(kms_flip:6084) DEBUG: name = flip
last_ts = 215.863739
last_received_ts = 215.863373
last_seq = 405
current_ts = 215.880417
current_received_ts = 215.880051
current_seq = 406
count = 142
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 229us
(kms_flip:6084) DEBUG: name = flip
last_ts = 215.880417
last_received_ts = 215.880051
last_seq = 406
current_ts = 215.897079
current_received_ts = 215.896698
current_seq = 407
count = 143
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 237us
(kms_flip:6084) DEBUG: name = flip
last_ts = 215.897079
last_received_ts = 215.896698
last_seq = 407
current_ts = 215.913757
current_received_ts = 215.913361
current_seq = 408
count = 144
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 240us
(kms_flip:6084) DEBUG: name = flip
last_ts = 215.913757
last_received_ts = 215.913361
last_seq = 408
current_ts = 215.930405
current_received_ts = 215.930054
current_seq = 409
count = 145
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 25us
(kms_flip:6084) DEBUG: name = flip
last_ts = 215.930405
last_received_ts = 215.930054
last_seq = 409
current_ts = 215.947083
current_received_ts = 215.946701
current_seq = 410
count = 146
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 236us
(kms_flip:6084) DEBUG: name = flip
last_ts = 215.947083
last_received_ts = 215.946701
last_seq = 410
current_ts = 215.963745
current_received_ts = 215.963364
current_seq = 411
count = 147
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 237us
(kms_flip:6084) DEBUG: name = flip
last_ts = 215.963745
last_received_ts = 215.963364
last_seq = 411
current_ts = 215.980408
current_received_ts = 215.980209
current_seq = 412
count = 148
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 58us
(kms_flip:6084) DEBUG: name = flip
last_ts = 215.980408
last_received_ts = 215.980209
last_seq = 412
current_ts = 215.997086
current_received_ts = 215.996719
current_seq = 413
count = 149
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 40us
(kms_flip:6084) DEBUG: name = flip
last_ts = 215.997086
last_received_ts = 215.996719
last_seq = 413
current_ts = 216.013763
current_received_ts = 216.013397
current_seq = 414
count = 150
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 234us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.013763
last_received_ts = 216.013397
last_seq = 414
current_ts = 216.030411
current_received_ts = 216.030045
current_seq = 415
count = 151
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 239us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.030411
last_received_ts = 216.030045
last_seq = 415
current_ts = 216.047089
current_received_ts = 216.046722
current_seq = 416
count = 152
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 45us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.047089
last_received_ts = 216.046722
last_seq = 416
current_ts = 216.063766
current_received_ts = 216.063385
current_seq = 417
count = 153
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 27us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.063766
last_received_ts = 216.063385
last_seq = 417
current_ts = 216.080414
current_received_ts = 216.080048
current_seq = 418
count = 154
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 30us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.080414
last_received_ts = 216.080048
last_seq = 418
current_ts = 216.097092
current_received_ts = 216.096725
current_seq = 419
count = 155
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 26us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.097092
last_received_ts = 216.096725
last_seq = 419
current_ts = 216.113754
current_received_ts = 216.113388
current_seq = 420
count = 156
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 27us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.113754
last_received_ts = 216.113388
last_seq = 420
current_ts = 216.130417
current_received_ts = 216.130051
current_seq = 421
count = 157
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 27us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.130417
last_received_ts = 216.130051
last_seq = 421
current_ts = 216.147095
current_received_ts = 216.146713
current_seq = 422
count = 158
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 27us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.147095
last_received_ts = 216.146713
last_seq = 422
current_ts = 216.163757
current_received_ts = 216.163391
current_seq = 423
count = 159
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 28us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.163757
last_received_ts = 216.163391
last_seq = 423
current_ts = 216.180435
current_received_ts = 216.180054
current_seq = 424
count = 160
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 26us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.180435
last_received_ts = 216.180054
last_seq = 424
current_ts = 216.197098
current_received_ts = 216.196732
current_seq = 425
count = 161
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 26us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.197098
last_received_ts = 216.196732
last_seq = 425
current_ts = 216.213760
current_received_ts = 216.213394
current_seq = 426
count = 162
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 27us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.213760
last_received_ts = 216.213394
last_seq = 426
current_ts = 216.230438
current_received_ts = 216.230057
current_seq = 427
count = 163
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 27us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.230438
last_received_ts = 216.230057
last_seq = 427
current_ts = 216.247101
current_received_ts = 216.246658
current_seq = 428
count = 164
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 26us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.247101
last_received_ts = 216.246658
last_seq = 428
current_ts = 216.263763
current_received_ts = 216.263321
current_seq = 429
count = 165
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 26us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.263763
last_received_ts = 216.263321
last_seq = 429
current_ts = 216.280441
current_received_ts = 216.280060
current_seq = 430
count = 166
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 26us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.280441
last_received_ts = 216.280060
last_seq = 430
current_ts = 216.297104
current_received_ts = 216.296661
current_seq = 431
count = 167
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 28us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.297104
last_received_ts = 216.296661
last_seq = 431
current_ts = 216.313766
current_received_ts = 216.313324
current_seq = 432
count = 168
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 27us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.313766
last_received_ts = 216.313324
last_seq = 432
current_ts = 216.330429
current_received_ts = 216.329987
current_seq = 433
count = 169
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 34us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.330429
last_received_ts = 216.329987
last_seq = 433
current_ts = 216.347107
current_received_ts = 216.346725
current_seq = 434
count = 170
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 246us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.347107
last_received_ts = 216.346725
last_seq = 434
current_ts = 216.363770
current_received_ts = 216.363403
current_seq = 435
count = 171
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 252us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.363770
last_received_ts = 216.363403
last_seq = 435
current_ts = 216.380432
current_received_ts = 216.380066
current_seq = 436
count = 172
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 237us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.380432
last_received_ts = 216.380066
last_seq = 436
current_ts = 216.397110
current_received_ts = 216.396744
current_seq = 437
count = 173
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 279us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.397110
last_received_ts = 216.396744
last_seq = 437
current_ts = 216.413773
current_received_ts = 216.413406
current_seq = 438
count = 174
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 250us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.413773
last_received_ts = 216.413406
last_seq = 438
current_ts = 216.430435
current_received_ts = 216.430069
current_seq = 439
count = 175
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 232us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.430435
last_received_ts = 216.430069
last_seq = 439
current_ts = 216.447113
current_received_ts = 216.446732
current_seq = 440
count = 176
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 255us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.447113
last_received_ts = 216.446732
last_seq = 440
current_ts = 216.463776
current_received_ts = 216.463394
current_seq = 441
count = 177
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 257us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.463776
last_received_ts = 216.463394
last_seq = 441
current_ts = 216.480438
current_received_ts = 216.480072
current_seq = 442
count = 178
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 253us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.480438
last_received_ts = 216.480072
last_seq = 442
current_ts = 216.497116
current_received_ts = 216.496735
current_seq = 443
count = 179
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 279us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.497116
last_received_ts = 216.496735
last_seq = 443
current_ts = 216.513779
current_received_ts = 216.513412
current_seq = 444
count = 180
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 250us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.513779
last_received_ts = 216.513412
last_seq = 444
current_ts = 216.530457
current_received_ts = 216.530075
current_seq = 445
count = 181
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 27us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.530457
last_received_ts = 216.530075
last_seq = 445
current_ts = 216.547119
current_received_ts = 216.546661
current_seq = 446
count = 182
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 250us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.547119
last_received_ts = 216.546661
last_seq = 446
current_ts = 216.563782
current_received_ts = 216.563416
current_seq = 447
count = 183
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 249us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.563782
last_received_ts = 216.563416
last_seq = 447
current_ts = 216.580460
current_received_ts = 216.580078
current_seq = 448
count = 184
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 248us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.580460
last_received_ts = 216.580078
last_seq = 448
current_ts = 216.597122
current_received_ts = 216.596741
current_seq = 449
count = 185
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 322us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.597122
last_received_ts = 216.596741
last_seq = 449
current_ts = 216.613785
current_received_ts = 216.613419
current_seq = 450
count = 186
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 245us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.613785
last_received_ts = 216.613419
last_seq = 450
current_ts = 216.630447
current_received_ts = 216.630081
current_seq = 451
count = 187
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 27us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.630447
last_received_ts = 216.630081
last_seq = 451
current_ts = 216.647125
current_received_ts = 216.646667
current_seq = 452
count = 188
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 26us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.647125
last_received_ts = 216.646667
last_seq = 452
current_ts = 216.663788
current_received_ts = 216.663345
current_seq = 453
count = 189
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 26us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.663788
last_received_ts = 216.663345
last_seq = 453
current_ts = 216.680450
current_received_ts = 216.680054
current_seq = 454
count = 190
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 30us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.680450
last_received_ts = 216.680054
last_seq = 454
current_ts = 216.697128
current_received_ts = 216.696747
current_seq = 455
count = 191
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 26us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.697128
last_received_ts = 216.696747
last_seq = 455
current_ts = 216.713791
current_received_ts = 216.713348
current_seq = 456
count = 192
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 27us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.713791
last_received_ts = 216.713348
last_seq = 456
current_ts = 216.730453
current_received_ts = 216.730042
current_seq = 457
count = 193
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 32us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.730453
last_received_ts = 216.730042
last_seq = 457
current_ts = 216.747131
current_received_ts = 216.746674
current_seq = 458
count = 194
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 26us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.747131
last_received_ts = 216.746674
last_seq = 458
current_ts = 216.763794
current_received_ts = 216.763351
current_seq = 459
count = 195
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 27us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.763794
last_received_ts = 216.763351
last_seq = 459
current_ts = 216.780457
current_received_ts = 216.780045
current_seq = 460
count = 196
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 31us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.780457
last_received_ts = 216.780045
last_seq = 460
current_ts = 216.797134
current_received_ts = 216.796677
current_seq = 461
count = 197
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 26us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.797134
last_received_ts = 216.796677
last_seq = 461
current_ts = 216.813797
current_received_ts = 216.813461
current_seq = 462
count = 198
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 40us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.813797
last_received_ts = 216.813461
last_seq = 462
current_ts = 216.830460
current_received_ts = 216.830109
current_seq = 463
count = 199
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 39us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.830460
last_received_ts = 216.830109
last_seq = 463
current_ts = 216.847137
current_received_ts = 216.846771
current_seq = 464
count = 200
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 46us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.847137
last_received_ts = 216.846771
last_seq = 464
current_ts = 216.863800
current_received_ts = 216.863312
current_seq = 465
count = 201
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 26us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.863800
last_received_ts = 216.863312
last_seq = 465
current_ts = 216.880463
current_received_ts = 216.880096
current_seq = 466
count = 202
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 26us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.880463
last_received_ts = 216.880096
last_seq = 466
current_ts = 216.897141
current_received_ts = 216.896683
current_seq = 467
count = 203
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 25us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.897141
last_received_ts = 216.896683
last_seq = 467
current_ts = 216.913803
current_received_ts = 216.913528
current_seq = 468
count = 204
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 40us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.913803
last_received_ts = 216.913528
last_seq = 468
current_ts = 216.930466
current_received_ts = 216.930115
current_seq = 469
count = 205
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 39us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.930466
last_received_ts = 216.930115
last_seq = 469
current_ts = 216.947144
current_received_ts = 216.946777
current_seq = 470
count = 206
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 28us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.947144
last_received_ts = 216.946777
last_seq = 470
current_ts = 216.963806
current_received_ts = 216.963318
current_seq = 471
count = 207
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 27us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.963806
last_received_ts = 216.963318
last_seq = 471
current_ts = 216.980484
current_received_ts = 216.980103
current_seq = 472
count = 208
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 27us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.980484
last_received_ts = 216.980103
last_seq = 472
current_ts = 216.997147
current_received_ts = 216.996765
current_seq = 473
count = 209
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 26us
(kms_flip:6084) DEBUG: name = flip
last_ts = 216.997147
last_received_ts = 216.996765
last_seq = 473
current_ts = 217.013809
current_received_ts = 217.013382
current_seq = 474
count = 210
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 34us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.013809
last_received_ts = 217.013382
last_seq = 474
current_ts = 217.030472
current_received_ts = 217.030106
current_seq = 475
count = 211
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 26us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.030472
last_received_ts = 217.030106
last_seq = 475
current_ts = 217.047150
current_received_ts = 217.046692
current_seq = 476
count = 212
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 26us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.047150
last_received_ts = 217.046692
last_seq = 476
current_ts = 217.063828
current_received_ts = 217.063431
current_seq = 477
count = 213
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 26us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.063828
last_received_ts = 217.063431
last_seq = 477
current_ts = 217.080475
current_received_ts = 217.080048
current_seq = 478
count = 214
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 26us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.080475
last_received_ts = 217.080048
last_seq = 478
current_ts = 217.097153
current_received_ts = 217.096771
current_seq = 479
count = 215
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 26us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.097153
last_received_ts = 217.096771
last_seq = 479
current_ts = 217.113815
current_received_ts = 217.113373
current_seq = 480
count = 216
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 26us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.113815
last_received_ts = 217.113373
last_seq = 480
current_ts = 217.130478
current_received_ts = 217.130051
current_seq = 481
count = 217
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 26us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.130478
last_received_ts = 217.130051
last_seq = 481
current_ts = 217.147156
current_received_ts = 217.146774
current_seq = 482
count = 218
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 25us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.147156
last_received_ts = 217.146774
last_seq = 482
current_ts = 217.163818
current_received_ts = 217.163452
current_seq = 483
count = 219
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 26us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.163818
last_received_ts = 217.163452
last_seq = 483
current_ts = 217.180496
current_received_ts = 217.179993
current_seq = 484
count = 220
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 25us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.180496
last_received_ts = 217.179993
last_seq = 484
current_ts = 217.197159
current_received_ts = 217.196777
current_seq = 485
count = 221
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 26us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.197159
last_received_ts = 217.196777
last_seq = 485
current_ts = 217.213821
current_received_ts = 217.213379
current_seq = 486
count = 222
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 26us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.213821
last_received_ts = 217.213379
last_seq = 486
current_ts = 217.230484
current_received_ts = 217.230164
current_seq = 487
count = 223
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 40us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.230484
last_received_ts = 217.230164
last_seq = 487
current_ts = 217.247162
current_received_ts = 217.246796
current_seq = 488
count = 224
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 38us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.247162
last_received_ts = 217.246796
last_seq = 488
current_ts = 217.263824
current_received_ts = 217.263458
current_seq = 489
count = 225
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 27us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.263824
last_received_ts = 217.263458
last_seq = 489
current_ts = 217.280487
current_received_ts = 217.280045
current_seq = 490
count = 226
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 27us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.280487
last_received_ts = 217.280045
last_seq = 490
current_ts = 217.297165
current_received_ts = 217.296707
current_seq = 491
count = 227
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 26us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.297165
last_received_ts = 217.296707
last_seq = 491
current_ts = 217.313828
current_received_ts = 217.313385
current_seq = 492
count = 228
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 26us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.313828
last_received_ts = 217.313385
last_seq = 492
current_ts = 217.330490
current_received_ts = 217.330048
current_seq = 493
count = 229
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 27us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.330490
last_received_ts = 217.330048
last_seq = 493
current_ts = 217.347168
current_received_ts = 217.346710
current_seq = 494
count = 230
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 26us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.347168
last_received_ts = 217.346710
last_seq = 494
current_ts = 217.363831
current_received_ts = 217.363388
current_seq = 495
count = 231
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 26us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.363831
last_received_ts = 217.363388
last_seq = 495
current_ts = 217.380508
current_received_ts = 217.380127
current_seq = 496
count = 232
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 26us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.380508
last_received_ts = 217.380127
last_seq = 496
current_ts = 217.397171
current_received_ts = 217.396683
current_seq = 497
count = 233
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 27us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.397171
last_received_ts = 217.396683
last_seq = 497
current_ts = 217.413834
current_received_ts = 217.413467
current_seq = 498
count = 234
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 26us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.413834
last_received_ts = 217.413467
last_seq = 498
current_ts = 217.430496
current_received_ts = 217.430084
current_seq = 499
count = 235
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 29us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.430496
last_received_ts = 217.430084
last_seq = 499
current_ts = 217.447174
current_received_ts = 217.446747
current_seq = 500
count = 236
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 46us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.447174
last_received_ts = 217.446747
last_seq = 500
current_ts = 217.463837
current_received_ts = 217.463486
current_seq = 501
count = 237
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 37us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.463837
last_received_ts = 217.463486
last_seq = 501
current_ts = 217.480499
current_received_ts = 217.480148
current_seq = 502
count = 238
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 35us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.480499
last_received_ts = 217.480148
last_seq = 502
current_ts = 217.497177
current_received_ts = 217.496811
current_seq = 503
count = 239
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 33us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.497177
last_received_ts = 217.496811
last_seq = 503
current_ts = 217.513840
current_received_ts = 217.513489
current_seq = 504
count = 240
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 34us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.513840
last_received_ts = 217.513489
last_seq = 504
current_ts = 217.530502
current_received_ts = 217.530151
current_seq = 505
count = 241
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 34us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.530502
last_received_ts = 217.530151
last_seq = 505
current_ts = 217.547180
current_received_ts = 217.546814
current_seq = 506
count = 242
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 33us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.547180
last_received_ts = 217.546814
last_seq = 506
current_ts = 217.563843
current_received_ts = 217.563477
current_seq = 507
count = 243
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 27us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.563843
last_received_ts = 217.563477
last_seq = 507
current_ts = 217.580505
current_received_ts = 217.580078
current_seq = 508
count = 244
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 25us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.580505
last_received_ts = 217.580078
last_seq = 508
current_ts = 217.597183
current_received_ts = 217.596802
current_seq = 509
count = 245
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 25us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.597183
last_received_ts = 217.596802
last_seq = 509
current_ts = 217.613846
current_received_ts = 217.613403
current_seq = 510
count = 246
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 255us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.613846
last_received_ts = 217.613403
last_seq = 510
current_ts = 217.630508
current_received_ts = 217.630142
current_seq = 511
count = 247
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 240us
(kms_flip:6084) DEBUG: name = flip
last_ts = 217.630508
last_received_ts = 217.630142
last_seq = 511
current_ts = 217.647186
current_received_ts = 217.646805
current_seq = 512
count = 248
seq_step = 1
(kms_flip:6084) DEBUG: Vblank took 532us
(kms_flip:6084) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:930:
(kms_flip:6084) CRITICAL: Failed assertion: end - start < 500
(kms_flip:6084) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:6084) igt_core-INFO: Stack trace:
(kms_flip:6084) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:6084) igt_core-INFO:   #1 ../tests/kms_flip.c:925 run_test_on_crtc_set.constprop.0()
(kms_flip:6084) igt_core-INFO:   #2 ../tests/kms_flip.c:1891 run_test()
(kms_flip:6084) igt_core-INFO:   #3 ../tests/kms_flip.c:2162 __igt_unique____real_main2047()
(kms_flip:6084) igt_core-INFO:   #4 ../tests/kms_flip.c:2047 main()
(kms_flip:6084) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:6084) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:6084) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest D-HDMI-A3: FAIL (4.982s)
Subtest flip-vs-expired-vblank-interruptible: FAIL (28.143s)
(kms_flip:6084) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2352:
(kms_flip:6084) igt_kms-CRITICAL: Failed assertion: found_it
(kms_flip:6084) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor
(kms_flip:6084) igt_kms-CRITICAL: DPMS property not found on 539
Dmesg
<6> [213.322083] [IGT] kms_flip: starting dynamic subtest D-HDMI-A3
<7> [213.324572] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:557]
<7> [213.326271] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:558]
<7> [213.373754] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [213.373866] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe B]
<7> [213.373951] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]
<7> [213.374005] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.enable (expected yes, found no)
<7> [213.374136] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.active (expected yes, found no)
<7> [213.374240] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in cpu_transcoder (expected 2, found -1)
<7> [213.374338] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in lane_count (expected 4, found 0)
<7> [213.374434] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [213.374530] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [213.374622] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [213.374713] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [213.374803] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [213.374894] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [213.375051] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [213.375146] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [213.375237] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [213.375327] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [213.375418] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [213.375508] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [213.375598] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [213.375688] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [213.375777] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [213.375873] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [213.376046] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [213.376142] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [213.376232] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [213.376321] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [213.376413] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [213.376503] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [213.376592] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [213.376681] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [213.376771] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [213.376860] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [213.376956] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [213.377046] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [213.377135] xe 0000:03:00.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> [213.377226] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [213.377315] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [213.377406] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [213.377496] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [213.377586] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [213.377676] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in port_clock (expected 222750, found 0)
<7> [213.377766] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [213.377856] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [213.377951] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in avi infoframe
<7> [213.378040] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [213.378129] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [213.378131] xe 0000:03:00.0: colorspace: RGB
<7> [213.378132] xe 0000:03:00.0: scan mode: Underscan
<7> [213.378133] xe 0000:03:00.0: colorimetry: No Data
<7> [213.378134] xe 0000:03:00.0: picture aspect: No Data
<7> [213.378135] xe 0000:03:00.0: active aspect: Same as Picture
<7> [213.378137] xe 0000:03:00.0: itc: No Data
<7> [213.378138] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [213.378139] xe 0000:03:00.0: quantization range: Full
<7> [213.378140] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [213.378141] xe 0000:03:00.0: video code: 0
<7> [213.378142] xe 0000:03:00.0: ycc quantization range: Full
<7> [213.378143] xe 0000:03:00.0: hdmi content type: Graphics
<7> [213.378144] xe 0000:03:00.0: pixel repeat: 0
<7> [213.378145] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [213.378147] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [213.378236] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in spd infoframe
<7> [213.378325] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [213.378414] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [213.378415] xe 0000:03:00.0: vendor: Intel
<7> [213.378416] xe 0000:03:00.0: product: Discrete gfx
<7> [213.378417] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [213.378419] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [213.378507] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hdmi infoframe
<7> [213.378597] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [213.378685] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [213.378686] xe 0000:03:00.0: empty frame
<7> [213.378687] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [213.378778] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:387:pipe C] fastset requirement not met, forcing full modeset
<7> [213.378868] xe 0000:03:00.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:387:pipe C] releasing TC PLL 3
<7> [213.379027] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xd -> 0x1 (total dbuf slices 0xf), mbus joined? no->no
<7> [213.379159] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:387:pipe C] dbuf slices 0xc -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x4 -> 0x0
<7> [213.379310] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:381:cursor C] ddb (1989 - 2048) -> ( 0 - 0), size 59 -> 0
<7> [213.379464] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:387:pipe C] min cdclk: 192000 kHz -> 0 kHz
<7> [213.379637] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:387:pipe C] enable: no [modeset]
<7> [213.379788] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:271:plane 1C] fb: [NOFB], visible: no
<7> [213.379938] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:301:plane 2C] fb: [NOFB], visible: no
<7> [213.380072] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:331:plane 3C] fb: [NOFB], visible: no
<7> [213.380176] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:361:plane 4C] fb: [NOFB], visible: no
<7> [213.380274] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:371:plane 5C] fb: [NOFB], visible: no
<7> [213.380369] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:381:cursor C] fb: [NOFB], visible: no
<7> [213.381041] xe 0000:03:00.0: [drm:intel_audio_codec_disable [xe]] [CONNECTOR:539:HDMI-A-3][ENCODER:538:DDI TC3/PHY H] Disable audio codec on [CRTC:387:pipe C]
<7> [213.400066] xe 0000:03:00.0: [drm:intel_audio_component_get_eld [xe]] Not valid for port F
<7> [213.400594] xe 0000:03:00.0: [drm:intel_disable_transcoder [xe]] disabling pipe C
<7> [213.421256] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disable TC PLL 3 (active 0x4, on? 1) for [CRTC:387:pipe C]
<7> [213.424117] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disabling TC PLL 3
<7> [213.424189] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [213.425015] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [213.425501] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [213.425977] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [213.426408] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [213.426816] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [213.427250] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [213.427660] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [213.428094] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [213.428212] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [213.428288] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [213.428366] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [213.428652] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [213.428729] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [213.428806] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [213.428884] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [213.428968] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [213.429045] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:539:HDMI-A-3]
<7> [213.429161] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [213.429247] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [213.429415] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_C
<7> [213.429723] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x4 (CLKGATE_DIS_DSSDSC=0xaa000000)
<7> [213.429851] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:387:pipe C]
<7> [213.430254] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:505:pipe D]
<7> [213.430541] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:505:pipe D]
<7> [213.430560] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:539:HDMI-A-3]
<7> [213.430617] xe 0000:03:00.0: [drm:intel_hdmi_compute_clock [xe]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [213.430720] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:505:pipe D] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [213.430825] xe 0000:03:00.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:538:DDI TC3/PHY H] [CRTC:505:pipe D]
<7> [213.430935] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.enable (expected no, found yes)
<7> [213.431035] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.active (expected no, found yes)
<7> [213.431132] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in cpu_transcoder (expected -1, found 3)
<7> [213.431226] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in lane_count (expected 0, found 4)
<7> [213.431320] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [213.431411] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [213.431504] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [213.431599] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [213.431691] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [213.431780] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [213.431869] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [213.431967] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [213.432058] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [213.432148] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [213.432237] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [213.432327] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [213.432416] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [213.432504] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [213.432591] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [213.432682] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [213.432772] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [213.432862] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [213.432953] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [213.433043] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [213.433132] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [213.433221] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [213.433309] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [213.433399] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [213.433489] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [213.433576] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [213.433664] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [213.433753] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [213.433843] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [213.433936] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [213.434026] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [213.434116] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [213.434205] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [213.434294] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [213.434384] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in port_clock (expected 0, found 222750)
<7> [213.434474] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [213.434563] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006)
<7> [213.434653] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in avi infoframe
<7> [213.434743] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [213.434833] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [213.434922] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [213.434923] xe 0000:03:00.0: colorspace: RGB
<7> [213.434925] xe 0000:03:00.0: scan mode: Underscan
<7> [213.434926] xe 0000:03:00.0: colorimetry: No Data
<7> [213.434930] xe 0000:03:00.0: picture aspect: No Data
<7> [213.434931] xe 0000:03:00.0: active aspect: Same as Picture
<7> [213.434932] xe 0000:03:00.0: itc: No Data
<7> [213.434933] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [213.434935] xe 0000:03:00.0: quantization range: Full
<7> [213.434936] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [213.434937] xe 0000:03:00.0: video code: 0
<7> [213.434938] xe 0000:03:00.0: ycc quantization range: Full
<7> [213.434940] xe 0000:03:00.0: hdmi content type: Graphics
<7> [213.434941] xe 0000:03:00.0: pixel repeat: 0
<7> [213.434942] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [213.434944] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in spd infoframe
<7> [213.435040] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [213.435134] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [213.435226] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [213.435227] xe 0000:03:00.0: vendor: Intel
<7> [213.435229] xe 0000:03:00.0: product: Discrete gfx
<7> [213.435230] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [213.435231] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hdmi infoframe
<7> [213.435322] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [213.435412] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [213.435501] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [213.435503] xe 0000:03:00.0: empty frame
<7> [213.435504] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:505:pipe D] fastset requirement not met, forcing full modeset
<7> [213.435612] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0xf), mbus joined? no->no
<7> [213.435692] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:505:pipe D] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x8
<7> [213.435787] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:389:plane 1D] ddb ( 0 - 0) -> ( 0 - 1989), size 0 -> 1989
<7> [213.435862] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:499:cursor D] ddb ( 0 - 0) -> (1989 - 2048), size 0 -> 59
<7> [213.435938] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:389:plane 1D] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [213.436012] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:389:plane 1D] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 4, 9, 9, 17, 17, 17, 17, 0, 15, 0
<7> [213.436083] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:389:plane 1D] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 62, 62, 139, 139, 261, 261, 261, 261, 137, 230, 244
<7> [213.436156] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:389:plane 1D] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 123, 123, 245, 245, 367, 367, 0, 0, 138, 306, 306
<7> [213.436240] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:505:pipe D] data rate 594000 num active planes 1
<7> [213.436343] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 624 qgv_peak_bw: 48000
<7> [213.436439] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 624 qgv_peak_bw: 48000
<7> [213.436532] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 624
<7> [213.436637] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:505:pipe D] min cdclk: 0 kHz -> 192000 kHz
<7> [213.436756] xe 0000:03:00.0: [drm:intel_find_dpll [xe]] [CRTC:505:pipe D] allocated TC PLL 3
<7> [213.436850] xe 0000:03:00.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:505:pipe D] reserving TC PLL 3
<7> [213.436946] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:505:pipe D] enable: yes [modeset]
<7> [213.437047] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [213.437145] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: D, pipe bpp: 36, dithering: 0
<7> [213.437237] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [213.437331] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [213.437422] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [213.437516] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [213.437611] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [213.437699] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] GCP: 0x6
<7> [213.437793] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [213.437795] xe 0000:03:00.0: colorspace: RGB
<7> [213.437796] xe 0000:03:00.0: scan mode: Underscan
<7> [213.437797] xe 0000:03:00.0: colorimetry: No Data
<7> [213.437798] xe 0000:03:00.0: picture aspect: No Data
<7> [213.437799] xe 0000:03:00.0: active aspect: Same as Picture
<7> [213.437801] xe 0000:03:00.0: itc: No Data
<7> [213.437802] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [213.437803] xe 0000:03:00.0: quantization range: Full
<7> [213.437804] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [213.437805] xe 0000:03:00.0: video code: 0
<7> [213.437806] xe 0000:03:00.0: ycc quantization range: Full
<7> [213.437807] xe 0000:03:00.0: hdmi content type: Graphics
<7> [213.437808] xe 0000:03:00.0: pixel repeat: 0
<7> [213.437809] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [213.437810] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [213.437812] xe 0000:03:00.0: vendor: Intel
<7> [213.437813] xe 0000:03:00.0: product: Discrete gfx
<7> [213.437814] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [213.437815] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [213.437816] xe 0000:03:00.0: empty frame
<7> [213.437817] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 10 00 07 00 66 10 00 01 00 00 00 00 00 00 00 00
<7> [213.437910] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 05 e3 cd 0c 32 38 45 38 35 30 09 07 07 00 00 00
<7> [213.438016] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [213.438109] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [213.438123] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [213.438199] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 0, guardband: 45 vsync start: 42, vsync end: 37
<7> [213.438292] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1080, vmax vblank: 1080, vmin vtotal: 1125, vmax vtotal: 1125
<7> [213.438384] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [213.438475] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [213.438566] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [213.438656] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1083-1088 vt=1125, flags=0x9
<7> [213.438747] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x40 0x9
<7> [213.438836] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1083-1088 vt=1125, flags=0x9
<7> [213.438933] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port clock: 222750, pipe src: 1920x1080+0+0, pixel rate 148500, min cdclk 192000
<7> [213.439025] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0
<7> [213.439113] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [213.439199] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [213.439291] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [213.439380] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: no, tbt_mode: no
<7> [213.439469] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] c20pll_hw_state:
<7> [213.439558] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] tx[0] = 0xbe88, tx[1] = 0x5800, tx[2] = 0x0000
<7> [213.439646] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [213.439734] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[0] = 0x40e8
<7> [213.439824] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[1] = 0x7d20
<7> [213.439913] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[2] = 0x0a06
<7> [213.440027] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[3] = 0x8f1c
<7> [213.440119] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[4] = 0x0000
<7> [213.440207] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[5] = 0x0000
<7> [213.440296] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[6] = 0x2200
<7> [213.440388] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[7] = 0xffff
<7> [213.440478] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[8] = 0x0400
<7> [213.440568] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[9] = 0x0000
<7> [213.440657] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[10] = 0x0001
<7> [213.440746] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vdr: custom width: 0x00, serdes rate: 0x00, hdmi rate: 0x00
<7> [213.440839] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [213.440932] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [213.441024] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [213.441110] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [213.441199] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [213.441289] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [213.441374] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [213.441464] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [213.441554] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [213.441644] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [213.441735] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [213.441823] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [213.441910] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [213.442008] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:389:plane 1D] fb: [FB:557] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [213.442100] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [213.442190] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [213.442282] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:419:plane 2D] fb: [NOFB], visible: no
<7> [213.442369] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:449:plane 3D] fb: [NOFB], visible: no
<7> [213.442461] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:479:plane 4D] fb: [NOFB], visible: no
<7> [213.442551] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:489:plane 5D] fb: [NOFB], visible: no
<7> [213.442641] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:499:cursor D] fb: [NOFB], visible: no
<7> [213.443269] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [213.443406] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_D
<7> [213.443547] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x8 (CLKGATE_DIS_DSSDSC=0x2a000000)
<7> [213.443653] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [213.443742] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [213.443827] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [213.443909] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [213.444001] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [213.444082] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [213.444162] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [213.444241] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [213.444319] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [213.444404] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [213.444484] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [213.444562] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [213.444639] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [213.444717] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [213.444792] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [213.444870] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [213.444960] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x3
<7> [213.445122] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [213.445218] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enable TC PLL 3 (active 0x8, on? 0) for [CRTC:505:pipe D]
<7> [213.445310] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enabling TC PLL 3
<7> [213.447800] xe 0000:03:00.0: [drm:intel_enable_transcoder [xe]] enabling pipe D
<7> [213.466149] xe 0000:03:00.0: [drm:intel_audio_codec_enable [xe]] [CONNECTOR:539:HDMI-A-3][ENCODER:538:DDI TC3/PHY H] Enable audio codec on [CRTC:505:pipe D], 32 bytes ELD
<7> [213.478637] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [213.483008] xe 0000:03:00.0: [drm:audio_config_hdmi_pixel_clock [xe]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [213.483257] xe 0000:03:00.0: [drm:hsw_audio_config_update [xe]] using automatic N
<7> [213.483841] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:539:HDMI-A-3]
<7> [213.484233] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:505:pipe D]
<6> [218.322211] [IGT] kms_flip: finished subtest D-HDMI-A3, FAIL
<6> [218.330475] [IGT] kms_flip: finished subtest flip-vs-expired-vblank-interruptible, FAIL
<7> [218.338142] xe 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:557] from all active usage due to RMFB ioctl
<7> [218.338318] xe 0000:03:00.0: [drm:atomic_remove_fb] Disabling [PLANE:389:plane 1D] because [FB:557] is removed
<7> [218.338353] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:389:plane 1D] ddb ( 0 - 1989) -> ( 0 - 0), size 1989 -> 0
<7> [218.338454] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:389:plane 1D] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [218.338530] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:389:plane 1D] lines 4, 4, 9, 9, 17, 17, 17, 17, 0, 15, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [218.338604] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:389:plane 1D] blocks 62, 62, 139, 139, 261, 261, 261, 261, 137, 230, 244 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [218.338673] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:389:plane 1D] min_ddb 123, 123, 245, 245, 367, 367, 0, 0, 138, 306, 306 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [218.338766] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:505:pipe D] data rate 0 num active planes 0
<7> [218.338864] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 0 qgv_peak_bw: 48000
<7> [218.339439] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 0 qgv_peak_bw: 48000
<7> [218.339961] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 0
<7> [218.353472] xe 0000:03:00.0: [drm:intel_hdmi_compute_clock [xe]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [218.354015] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [218.354496] xe 0000:03:00.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:538:DDI TC3/PHY H] [CRTC:151:pipe A]
<7> [218.354954] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [218.355346] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [218.355701] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [218.356113] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [218.356448] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [218.356752] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [218.357087] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [218.357370] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [218.357640] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [218.357926] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [218.358191] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [218.358436] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [218.358674] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [218.358924] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [218.359151] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [218.359362] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [218.359571] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [218.359773] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [218.360045] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [218.360242] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [218.360432] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [218.360619] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [218.360799] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [218.361006] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [218.361182] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [218.361350] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [218.361513] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [218.361667] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [218.361822] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [218.361991] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [218.362134] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [218.362275] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [218.362424] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [218.362562] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [218.362698] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [218.362828] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [218.362969] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [218.363099] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [218.363217] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 0, found 222750)
<7> [218.363334] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [218.363452] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006)
<7> [218.363570] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in avi infoframe
<7> [218.363678] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [218.363787] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [218.363903] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [218.363906] xe 0000:03:00.0: colorspace: RGB
<7> [218.363908] xe 0000:03:00.0: scan mode: Underscan
<7> [218.363910] xe 0000:03:00.0: colorimetry: No Data
<7> [218.363911] xe 0000:03:00.0: picture aspect: No Data
<7> [218.363913] xe 0000:03:00.0: active aspect: Same as Picture
<7> [218.363915] xe 0000:03:00.0: itc: No Data
<7> [218.363916] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [218.363918] xe 0000:03:00.0: quantization range: Full
<7> [218.363920] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [218.363922] xe 0000:03:00.0: video code: 0
<7> [218.363924] xe 0000:03:00.0: ycc quantization range: Full
<7> [218.363925] xe 0000:03:00.0: hdmi content type: Graphics
<7> [218.363928] xe 0000:03:00.0: pixel repeat: 0
<7> [218.363929] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [218.363931] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in spd infoframe
<7> [218.364042] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [218.364149] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [218.364245] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [218.364247] xe 0000:03:00.0: vendor: Intel
<7> [218.364248] xe 0000:03:00.0: product: Discrete gfx
<7> [218.364250] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [218.364251] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hdmi infoframe
<7> [218.364349] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [218.364443] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [218.364541] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [218.364543] xe 0000:03:00.0: empty frame
<7> [218.364545] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [218.364641] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.enable (expected yes, found no)
<7> [218.364737] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.active (expected yes, found no)
<7> [218.364833] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in cpu_transcoder (expected 3, found -1)
<7> [218.364995] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in lane_count (expected 4, found 0)
<7> [218.365082] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [218.365173] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [218.365261] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [218.365351] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [218.365440] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [218.365530] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [218.365619] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [218.365705] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [218.365794] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [218.365888] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [218.365977] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [218.366066] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [218.366155] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [218.366241] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [218.366327] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [218.366412] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [218.366499] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [218.366584] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [218.366668] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [218.366757] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [218.366845] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [218.366941] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [218.367030] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [218.367115] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [218.367203] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [218.367290] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [218.367377] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [218.367464] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [218.367549] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [218.367637] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [218.367726] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [218.367815] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [218.367917] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [218.368006] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [218.368096] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in port_clock (expected 222750, found 0)
<7> [218.368190] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [218.368283] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [218.368374] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in avi infoframe
<7> [218.368460] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [218.368548] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [218.368549] xe 0000:03:00.0: colorspace: RGB
<7> [218.368551] xe 0000:03:00.0: scan mode: Underscan
<7> [218.368552] xe 0000:03:00.0: colorimetry: No Data
<7> [218.368553] xe 0000:03:00.0: picture aspect: No Data
<7> [218.368555] xe 0000:03:00.0: active aspect: Same as Picture
<7> [218.368556] xe 0000:03:00.0: itc: No Data
<7> [218.368557] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [218.368558] xe 0000:03:00.0: quantization range: Full
<7> [218.368560] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [218.368561] xe 0000:03:00.0: video code: 0
<7> [218.368562] xe 0000:03:00.0: ycc quantization range: Full
<7> [218.368563] xe 0000:03:00.0: hdmi content type: Graphics
<7> [218.368565] xe 0000:03:00.0: pixel repeat: 0
<7> [218.368566] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [218.368567] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [218.368656] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in spd infoframe
<7> [218.368747] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [218.368837] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [218.368839] xe 0000:03:00.0: vendor: Intel
<7> [218.368840] xe 0000:03:00.0: product: Discrete gfx
<7> [218.368841] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [218.368843] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [218.368941] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:505:pipe D] fastset requirement not met in hdmi infoframe
<7> [218.369031] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [218.369117] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [218.369118] xe 0000:03:00.0: empty frame
<7> [218.369119] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [218.369208] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:505:pipe D] fastset requirement not met, forcing full modeset
<7> [218.369299] xe 0000:03:00.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:505:pipe D] releasing TC PLL 3
<7> [218.369430] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x3 -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [218.369515] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:151:pipe A] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x8 -> 0x1
<7> [218.369594] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:505:pipe D] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x8 -> 0x1
<7> [218.369675] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 4037), size 0 -> 4037
<7> [218.369752] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:145:cursor A] ddb ( 0 - 0) -> (4037 - 4096), size 0 -> 59
<7> [218.369828] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:35:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [218.369924] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:35:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 3, 9, 9, 17, 17, 17, 17, 0, 15, 0
<7> [218.369998] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:35:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 49, 145, 145, 273, 273, 273, 273, 30, 241, 255
<7> [218.370070] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:35:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 55, 161, 161, 302, 302, 0, 0, 31, 267, 267
<7> [218.370143] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:499:cursor D] ddb (1989 - 2048) -> ( 0 - 0), size 59 -> 0
<7> [218.370226] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:151:pipe A] data rate 594000 num active planes 1
<7> [218.370329] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 624 qgv_peak_bw: 48000
<7> [218.370427] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 624 qgv_peak_bw: 48000
<7> [218.370522] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 624
<7> [218.370617] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:151:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [218.370713] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:505:pipe D] min cdclk: 192000 kHz -> 0 kHz
<7> [218.370834] xe 0000:03:00.0: [drm:intel_find_dpll [xe]] [CRTC:151:pipe A] allocated TC PLL 3
<7> [218.370940] xe 0000:03:00.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:151:pipe A] reserving TC PLL 3
<7> [218.371034] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:151:pipe A] enable: yes [modeset]
<7> [218.371130] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [218.371229] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: A, pipe bpp: 36, dithering: 0
<7> [218.371323] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [218.371418] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [218.371509] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [218.371601] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [218.371694] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [218.371788] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] GCP: 0x6
<7> [218.371881] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [218.371883] xe 0000:03:00.0: colorspace: RGB
<7> [218.371884] xe 0000:03:00.0: scan mode: Underscan
<7> [218.371885] xe 0000:03:00.0: colorimetry: No Data
<7> [218.371887] xe 0000:03:00.0: picture aspect: No Data
<7> [218.371888] xe 0000:03:00.0: active aspect: Same as Picture
<7> [218.371890] xe 0000:03:00.0: itc: No Data
<7> [218.371891] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [218.371892] xe 0000:03:00.0: quantization range: Full
<7> [218.371894] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [218.371895] xe 0000:03:00.0: video code: 0
<7> [218.371897] xe 0000:03:00.0: ycc quantization range: Full
<7> [218.371898] xe 0000:03:00.0: hdmi content type: Graphics
<7> [218.371899] xe 0000:03:00.0: pixel repeat: 0
<7> [218.371901] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [218.371902] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [218.371904] xe 0000:03:00.0: vendor: Intel
<7> [218.371905] xe 0000:03:00.0: product: Discrete gfx
<7> [218.371906] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [218.371908] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [218.371909] xe 0000:03:00.0: empty frame
<7> [218.371911] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 10 00 07 00 66 10 00 01 00 00 00 00 00 00 00 00
<7> [218.372003] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 05 e3 cd 0c 32 38 45 38 35 30 09 07 07 00 00 00
<7> [218.372095] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [218.372187] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [218.372278] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 0, guardband: 45 vsync start: 42, vsync end: 37
<7> [218.372369] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1080, vmax vblank: 1080, vmin vtotal: 1125, vmax vtotal: 1125
<7> [218.372460] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [218.372552] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [218.372645] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [218.372736] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1083-1088 vt=1125, flags=0x9
<7> [218.372828] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x40 0x9
<7> [218.372928] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1083-1088 vt=1125, flags=0x9
<7> [218.373020] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port clock: 222750, pipe src: 1920x1080+0+0, pixel rate 148500, min cdclk 192000
<7> [218.373112] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0
<7> [218.373203] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [218.373296] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [218.373386] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [218.373477] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: no, tbt_mode: no
<7> [218.373568] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] c20pll_hw_state:
<7> [218.373658] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] tx[0] = 0xbe88, tx[1] = 0x5800, tx[2] = 0x0000
<7> [218.373749] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [218.373839] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[0] = 0x40e8
<7> [218.373937] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[1] = 0x7d20
<7> [218.374027] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[2] = 0x0a06
<7> [218.374117] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[3] = 0x8f1c
<7> [218.374208] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[4] = 0x0000
<7> [218.374294] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[5] = 0x0000
<7> [218.374380] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[6] = 0x2200
<7> [218.374470] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[7] = 0xffff
<7> [218.374560] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[8] = 0x0400
<7> [218.374651] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[9] = 0x0000
<7> [218.374741] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[10] = 0x0001
<7> [218.374833] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vdr: custom width: 0x00, serdes rate: 0x00, hdmi rate: 0x00
<7> [218.374935] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [218.375027] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [218.375115] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [218.375203] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [218.375290] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [218.375380] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [218.375469] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [218.375562] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [218.375652] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [218.375741] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [218.375829] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [218.375932] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [218.376023] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [218.376112] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:35:plane 1A] fb: [FB:555] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [218.376201] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [218.376291] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [218.376382] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [218.376473] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [218.376560] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [218.376649] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [218.376739] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [218.376831] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:505:pipe D] enable: no [modeset]
<7> [218.376931] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:389:plane 1D] fb: [NOFB], visible: no
<7> [218.377027] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:419:plane 2D] fb: [NOFB], visible: no
<7> [218.377117] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:449:plane 3D] fb: [NOFB], visible: no
<7> [218.377207] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:479:plane 4D] fb: [NOFB], visible: no
<7> [218.377298] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:489:plane 5D] fb: [NOFB], visible: no
<7> [218.377390] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:499:cursor D] fb: [NOFB], visible: no
<7> [218.377811] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_A
<7> [218.377968] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x1 (CLKGATE_DIS_DSSDSC=0x28000000)
<7> [218.378073] xe 0000:03:00.0: [drm:intel_audio_codec_disable [xe]] [CONNECTOR:539:HDMI-A-3][ENCODER:538:DDI TC3/PHY H] Disable audio codec on [CRTC:505:pipe D]
<7> [218.399716] xe 0000:03:00.0: [drm:intel_audio_component_get_eld [xe]] Not valid for port F
<7> [218.400132] xe 0000:03:00.0: [drm:intel_disable_transcoder [xe]] disabling pipe D
<7> [218.420972] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disable TC PLL 3 (active 0x8, on? 1) for [CRTC:505:pipe D]
<7> [218.422955] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disabling TC PLL 3
<7> [218.423457] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [218.423662] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [218.423851] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [218.424066] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [218.424257] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [218.424276] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [218.424439] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [218.424621] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [218.424802] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [218.424994] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [218.425166] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [218.425336] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [218.425505] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [218.425670] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [218.425830] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [218.426010] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [218.426170] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [218.426351] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [218.426818] xe 0000:03:00.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [218.426980] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: yes)
<7> [218.427345] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enable TC PLL 3 (active 0x1, on? 0) for [CRTC:151:pipe A]
<7> [218.427510] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enabling TC PLL 3
<7> [218.430339] xe 0000:03:00.0: [drm:intel_enable_transcoder [xe]] enabling pipe A
<7> [218.448162] xe 0000:03:00.0: [drm:intel_audio_codec_enable [xe]] [CONNECTOR:539:HDMI-A-3][ENCODER:538:DDI TC3/PHY H] Enable audio codec on [CRTC:151:pipe A], 32 bytes ELD
<7> [218.462948] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [218.464757] xe 0000:03:00.0: [drm:audio_config_hdmi_pixel_clock [xe]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [218.465319] xe 0000:03:00.0: [drm:hsw_audio_config_update [xe]] using automatic N
<7> [218.466259] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:539:HDMI-A-3]
<7> [218.466862] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:151:pipe A]
<7> [218.471556] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_D
<7> [218.472112] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x8 (CLKGATE_DIS_DSSDSC=0xa8000000)
<7> [218.472911] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:505:pipe D]
<7> [218.473744] xe 0000:03:00.0: [drm:drm_client_dev_restore] fbdev: ret=0
<6> [218.475484] [IGT] kms_flip: exiting, ret=98
Created at 2026-03-21 10:24:57