Results for igt@kms_flip@flip-vs-expired-vblank@b-hdmi-a1

Result: Fail

git-log-oneline i915_display_info10 igt_runner10 runtimes10 results10.json results10-i915-load.json guc_logs10.tar i915_display_info_post_exec10 boot10 dmesg10

DetailValue
Duration 6.90 seconds
Hostname 
shard-snb6
Igt-Version 
IGT-Version: 2.3-gab901e39e (x86_64) (Linux: 7.0.0-rc3-CI_DRM_18129-g215a6363df58+ x86_64)
Out 
Starting dynamic subtest: B-HDMI-A1
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:921 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1887 run_test()
  #3 ../tests/kms_flip.c:2120 __igt_unique____real_main2043()
  #4 ../tests/kms_flip.c:2043 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest B-HDMI-A1: FAIL (6.897s)
Subtest flip-vs-expired-vblank: FAIL (30.149s)
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../lib/igt_kms.c:2351 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: B-HDMI-A1
(kms_flip:2547) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:926:
(kms_flip:2547) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest B-HDMI-A1 failed.
**** DEBUG ****
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.041290
last_received_ts = 281.040863
last_seq = 9280
current_ts = 281.057922
current_received_ts = 281.057465
current_seq = 9281
count = 258
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.057922
last_received_ts = 281.057465
last_seq = 9281
current_ts = 281.074554
current_received_ts = 281.074127
current_seq = 9282
count = 259
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.074554
last_received_ts = 281.074127
last_seq = 9282
current_ts = 281.091187
current_received_ts = 281.090759
current_seq = 9283
count = 260
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.091187
last_received_ts = 281.090759
last_seq = 9283
current_ts = 281.107849
current_received_ts = 281.107391
current_seq = 9284
count = 261
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.107849
last_received_ts = 281.107391
last_seq = 9284
current_ts = 281.124481
current_received_ts = 281.123993
current_seq = 9285
count = 262
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.124481
last_received_ts = 281.123993
last_seq = 9285
current_ts = 281.141113
current_received_ts = 281.140656
current_seq = 9286
count = 263
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.141113
last_received_ts = 281.140656
last_seq = 9286
current_ts = 281.157745
current_received_ts = 281.157288
current_seq = 9287
count = 264
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.157745
last_received_ts = 281.157288
last_seq = 9287
current_ts = 281.174347
current_received_ts = 281.173920
current_seq = 9288
count = 265
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.174347
last_received_ts = 281.173920
last_seq = 9288
current_ts = 281.191010
current_received_ts = 281.190552
current_seq = 9289
count = 266
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.191010
last_received_ts = 281.190552
last_seq = 9289
current_ts = 281.207642
current_received_ts = 281.207184
current_seq = 9290
count = 267
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.207642
last_received_ts = 281.207184
last_seq = 9290
current_ts = 281.224274
current_received_ts = 281.223816
current_seq = 9291
count = 268
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.224274
last_received_ts = 281.223816
last_seq = 9291
current_ts = 281.240906
current_received_ts = 281.240479
current_seq = 9292
count = 269
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 7us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.240906
last_received_ts = 281.240479
last_seq = 9292
current_ts = 281.257538
current_received_ts = 281.257111
current_seq = 9293
count = 270
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.257538
last_received_ts = 281.257111
last_seq = 9293
current_ts = 281.274170
current_received_ts = 281.273712
current_seq = 9294
count = 271
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.274170
last_received_ts = 281.273712
last_seq = 9294
current_ts = 281.290802
current_received_ts = 281.290344
current_seq = 9295
count = 272
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.290802
last_received_ts = 281.290344
last_seq = 9295
current_ts = 281.307434
current_received_ts = 281.306946
current_seq = 9296
count = 273
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.307434
last_received_ts = 281.306946
last_seq = 9296
current_ts = 281.324066
current_received_ts = 281.323608
current_seq = 9297
count = 274
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.324066
last_received_ts = 281.323608
last_seq = 9297
current_ts = 281.340698
current_received_ts = 281.340240
current_seq = 9298
count = 275
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.340698
last_received_ts = 281.340240
last_seq = 9298
current_ts = 281.357330
current_received_ts = 281.356873
current_seq = 9299
count = 276
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 7us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.357330
last_received_ts = 281.356873
last_seq = 9299
current_ts = 281.373962
current_received_ts = 281.373505
current_seq = 9300
count = 277
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.373962
last_received_ts = 281.373505
last_seq = 9300
current_ts = 281.390594
current_received_ts = 281.390137
current_seq = 9301
count = 278
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.390594
last_received_ts = 281.390137
last_seq = 9301
current_ts = 281.407227
current_received_ts = 281.406769
current_seq = 9302
count = 279
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.407227
last_received_ts = 281.406769
last_seq = 9302
current_ts = 281.423859
current_received_ts = 281.423401
current_seq = 9303
count = 280
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.423859
last_received_ts = 281.423401
last_seq = 9303
current_ts = 281.440491
current_received_ts = 281.440033
current_seq = 9304
count = 281
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 6us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.440491
last_received_ts = 281.440033
last_seq = 9304
current_ts = 281.457123
current_received_ts = 281.456665
current_seq = 9305
count = 282
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.457123
last_received_ts = 281.456665
last_seq = 9305
current_ts = 281.473755
current_received_ts = 281.473297
current_seq = 9306
count = 283
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.473755
last_received_ts = 281.473297
last_seq = 9306
current_ts = 281.490387
current_received_ts = 281.489929
current_seq = 9307
count = 284
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.490387
last_received_ts = 281.489929
last_seq = 9307
current_ts = 281.507019
current_received_ts = 281.506592
current_seq = 9308
count = 285
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.507019
last_received_ts = 281.506592
last_seq = 9308
current_ts = 281.523651
current_received_ts = 281.523193
current_seq = 9309
count = 286
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.523651
last_received_ts = 281.523193
last_seq = 9309
current_ts = 281.540283
current_received_ts = 281.539825
current_seq = 9310
count = 287
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.540283
last_received_ts = 281.539825
last_seq = 9310
current_ts = 281.556915
current_received_ts = 281.556458
current_seq = 9311
count = 288
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.556915
last_received_ts = 281.556458
last_seq = 9311
current_ts = 281.573547
current_received_ts = 281.573090
current_seq = 9312
count = 289
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.573547
last_received_ts = 281.573090
last_seq = 9312
current_ts = 281.590179
current_received_ts = 281.589722
current_seq = 9313
count = 290
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.590179
last_received_ts = 281.589722
last_seq = 9313
current_ts = 281.606812
current_received_ts = 281.606354
current_seq = 9314
count = 291
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.606812
last_received_ts = 281.606354
last_seq = 9314
current_ts = 281.623444
current_received_ts = 281.622955
current_seq = 9315
count = 292
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.623444
last_received_ts = 281.622955
last_seq = 9315
current_ts = 281.640076
current_received_ts = 281.639618
current_seq = 9316
count = 293
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.640076
last_received_ts = 281.639618
last_seq = 9316
current_ts = 281.656708
current_received_ts = 281.656281
current_seq = 9317
count = 294
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.656708
last_received_ts = 281.656281
last_seq = 9317
current_ts = 281.673340
current_received_ts = 281.672882
current_seq = 9318
count = 295
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.673340
last_received_ts = 281.672882
last_seq = 9318
current_ts = 281.689972
current_received_ts = 281.689514
current_seq = 9319
count = 296
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.689972
last_received_ts = 281.689514
last_seq = 9319
current_ts = 281.706604
current_received_ts = 281.706177
current_seq = 9320
count = 297
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.706604
last_received_ts = 281.706177
last_seq = 9320
current_ts = 281.723236
current_received_ts = 281.722809
current_seq = 9321
count = 298
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.723236
last_received_ts = 281.722809
last_seq = 9321
current_ts = 281.739868
current_received_ts = 281.739441
current_seq = 9322
count = 299
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.739868
last_received_ts = 281.739441
last_seq = 9322
current_ts = 281.756500
current_received_ts = 281.756073
current_seq = 9323
count = 300
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.756500
last_received_ts = 281.756073
last_seq = 9323
current_ts = 281.773132
current_received_ts = 281.772705
current_seq = 9324
count = 301
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.773132
last_received_ts = 281.772705
last_seq = 9324
current_ts = 281.789764
current_received_ts = 281.789337
current_seq = 9325
count = 302
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.789764
last_received_ts = 281.789337
last_seq = 9325
current_ts = 281.806396
current_received_ts = 281.805908
current_seq = 9326
count = 303
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.806396
last_received_ts = 281.805908
last_seq = 9326
current_ts = 281.823029
current_received_ts = 281.822449
current_seq = 9327
count = 304
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 6us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.823029
last_received_ts = 281.822449
last_seq = 9327
current_ts = 281.839661
current_received_ts = 281.839050
current_seq = 9328
count = 305
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 5us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.839661
last_received_ts = 281.839050
last_seq = 9328
current_ts = 281.856293
current_received_ts = 281.855743
current_seq = 9329
count = 306
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 5us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.856293
last_received_ts = 281.855743
last_seq = 9329
current_ts = 281.872925
current_received_ts = 281.872345
current_seq = 9330
count = 307
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 6us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.872925
last_received_ts = 281.872345
last_seq = 9330
current_ts = 281.889557
current_received_ts = 281.889099
current_seq = 9331
count = 308
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.889557
last_received_ts = 281.889099
last_seq = 9331
current_ts = 281.906189
current_received_ts = 281.905731
current_seq = 9332
count = 309
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.906189
last_received_ts = 281.905731
last_seq = 9332
current_ts = 281.922821
current_received_ts = 281.922394
current_seq = 9333
count = 310
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.922821
last_received_ts = 281.922394
last_seq = 9333
current_ts = 281.939453
current_received_ts = 281.939056
current_seq = 9334
count = 311
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 12us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.939453
last_received_ts = 281.939056
last_seq = 9334
current_ts = 281.956085
current_received_ts = 281.955658
current_seq = 9335
count = 312
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.956085
last_received_ts = 281.955658
last_seq = 9335
current_ts = 281.972717
current_received_ts = 281.972290
current_seq = 9336
count = 313
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.972717
last_received_ts = 281.972290
last_seq = 9336
current_ts = 281.989349
current_received_ts = 281.988922
current_seq = 9337
count = 314
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 281.989349
last_received_ts = 281.988922
last_seq = 9337
current_ts = 282.005981
current_received_ts = 282.005554
current_seq = 9338
count = 315
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.005981
last_received_ts = 282.005554
last_seq = 9338
current_ts = 282.022614
current_received_ts = 282.022186
current_seq = 9339
count = 316
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.022614
last_received_ts = 282.022186
last_seq = 9339
current_ts = 282.039246
current_received_ts = 282.038818
current_seq = 9340
count = 317
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.039246
last_received_ts = 282.038818
last_seq = 9340
current_ts = 282.055878
current_received_ts = 282.055450
current_seq = 9341
count = 318
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.055878
last_received_ts = 282.055450
last_seq = 9341
current_ts = 282.072510
current_received_ts = 282.072083
current_seq = 9342
count = 319
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.072510
last_received_ts = 282.072083
last_seq = 9342
current_ts = 282.089142
current_received_ts = 282.088684
current_seq = 9343
count = 320
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.089142
last_received_ts = 282.088684
last_seq = 9343
current_ts = 282.105774
current_received_ts = 282.105347
current_seq = 9344
count = 321
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.105774
last_received_ts = 282.105347
last_seq = 9344
current_ts = 282.122406
current_received_ts = 282.121979
current_seq = 9345
count = 322
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 37us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.122406
last_received_ts = 282.121979
last_seq = 9345
current_ts = 282.139038
current_received_ts = 282.138611
current_seq = 9346
count = 323
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.139038
last_received_ts = 282.138611
last_seq = 9346
current_ts = 282.155670
current_received_ts = 282.155243
current_seq = 9347
count = 324
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.155670
last_received_ts = 282.155243
last_seq = 9347
current_ts = 282.172302
current_received_ts = 282.171875
current_seq = 9348
count = 325
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.172302
last_received_ts = 282.171875
last_seq = 9348
current_ts = 282.188934
current_received_ts = 282.188507
current_seq = 9349
count = 326
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.188934
last_received_ts = 282.188507
last_seq = 9349
current_ts = 282.205566
current_received_ts = 282.205139
current_seq = 9350
count = 327
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.205566
last_received_ts = 282.205139
last_seq = 9350
current_ts = 282.222198
current_received_ts = 282.221771
current_seq = 9351
count = 328
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 7us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.222198
last_received_ts = 282.221771
last_seq = 9351
current_ts = 282.238831
current_received_ts = 282.238403
current_seq = 9352
count = 329
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.238831
last_received_ts = 282.238403
last_seq = 9352
current_ts = 282.255463
current_received_ts = 282.255035
current_seq = 9353
count = 330
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.255463
last_received_ts = 282.255035
last_seq = 9353
current_ts = 282.272095
current_received_ts = 282.271667
current_seq = 9354
count = 331
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.272095
last_received_ts = 282.271667
last_seq = 9354
current_ts = 282.288727
current_received_ts = 282.288269
current_seq = 9355
count = 332
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.288727
last_received_ts = 282.288269
last_seq = 9355
current_ts = 282.305389
current_received_ts = 282.304901
current_seq = 9356
count = 333
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.305389
last_received_ts = 282.304901
last_seq = 9356
current_ts = 282.321991
current_received_ts = 282.321564
current_seq = 9357
count = 334
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.321991
last_received_ts = 282.321564
last_seq = 9357
current_ts = 282.338623
current_received_ts = 282.338196
current_seq = 9358
count = 335
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.338623
last_received_ts = 282.338196
last_seq = 9358
current_ts = 282.355255
current_received_ts = 282.354797
current_seq = 9359
count = 336
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.355255
last_received_ts = 282.354797
last_seq = 9359
current_ts = 282.371887
current_received_ts = 282.371460
current_seq = 9360
count = 337
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.371887
last_received_ts = 282.371460
last_seq = 9360
current_ts = 282.388519
current_received_ts = 282.388123
current_seq = 9361
count = 338
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.388519
last_received_ts = 282.388123
last_seq = 9361
current_ts = 282.405151
current_received_ts = 282.404724
current_seq = 9362
count = 339
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.405151
last_received_ts = 282.404724
last_seq = 9362
current_ts = 282.421814
current_received_ts = 282.421356
current_seq = 9363
count = 340
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.421814
last_received_ts = 282.421356
last_seq = 9363
current_ts = 282.438416
current_received_ts = 282.438019
current_seq = 9364
count = 341
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 10us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.438416
last_received_ts = 282.438019
last_seq = 9364
current_ts = 282.455078
current_received_ts = 282.454620
current_seq = 9365
count = 342
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 7us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.455078
last_received_ts = 282.454620
last_seq = 9365
current_ts = 282.471710
current_received_ts = 282.471100
current_seq = 9366
count = 343
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 7us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.471710
last_received_ts = 282.471100
last_seq = 9366
current_ts = 282.488342
current_received_ts = 282.487732
current_seq = 9367
count = 344
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.488342
last_received_ts = 282.487732
last_seq = 9367
current_ts = 282.504974
current_received_ts = 282.504364
current_seq = 9368
count = 345
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.504974
last_received_ts = 282.504364
last_seq = 9368
current_ts = 282.521576
current_received_ts = 282.521118
current_seq = 9369
count = 346
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.521576
last_received_ts = 282.521118
last_seq = 9369
current_ts = 282.538208
current_received_ts = 282.537659
current_seq = 9370
count = 347
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 11us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.538208
last_received_ts = 282.537659
last_seq = 9370
current_ts = 282.554840
current_received_ts = 282.554260
current_seq = 9371
count = 348
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 10us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.554840
last_received_ts = 282.554260
last_seq = 9371
current_ts = 282.571503
current_received_ts = 282.570923
current_seq = 9372
count = 349
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 7us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.571503
last_received_ts = 282.570923
last_seq = 9372
current_ts = 282.588135
current_received_ts = 282.587585
current_seq = 9373
count = 350
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.588135
last_received_ts = 282.587585
last_seq = 9373
current_ts = 282.604767
current_received_ts = 282.604187
current_seq = 9374
count = 351
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.604767
last_received_ts = 282.604187
last_seq = 9374
current_ts = 282.621399
current_received_ts = 282.620819
current_seq = 9375
count = 352
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 7us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.621399
last_received_ts = 282.620819
last_seq = 9375
current_ts = 282.638031
current_received_ts = 282.637451
current_seq = 9376
count = 353
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.638031
last_received_ts = 282.637451
last_seq = 9376
current_ts = 282.654663
current_received_ts = 282.654114
current_seq = 9377
count = 354
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 10us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.654663
last_received_ts = 282.654114
last_seq = 9377
current_ts = 282.671295
current_received_ts = 282.670746
current_seq = 9378
count = 355
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.671295
last_received_ts = 282.670746
last_seq = 9378
current_ts = 282.687927
current_received_ts = 282.687378
current_seq = 9379
count = 356
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.687927
last_received_ts = 282.687378
last_seq = 9379
current_ts = 282.704559
current_received_ts = 282.704041
current_seq = 9380
count = 357
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.704559
last_received_ts = 282.704041
last_seq = 9380
current_ts = 282.721191
current_received_ts = 282.720612
current_seq = 9381
count = 358
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.721191
last_received_ts = 282.720612
last_seq = 9381
current_ts = 282.737823
current_received_ts = 282.737274
current_seq = 9382
count = 359
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.737823
last_received_ts = 282.737274
last_seq = 9382
current_ts = 282.754456
current_received_ts = 282.753906
current_seq = 9383
count = 360
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.754456
last_received_ts = 282.753906
last_seq = 9383
current_ts = 282.771088
current_received_ts = 282.770538
current_seq = 9384
count = 361
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.771088
last_received_ts = 282.770538
last_seq = 9384
current_ts = 282.787720
current_received_ts = 282.787170
current_seq = 9385
count = 362
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.787720
last_received_ts = 282.787170
last_seq = 9385
current_ts = 282.804352
current_received_ts = 282.803802
current_seq = 9386
count = 363
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.804352
last_received_ts = 282.803802
last_seq = 9386
current_ts = 282.820984
current_received_ts = 282.820435
current_seq = 9387
count = 364
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.820984
last_received_ts = 282.820435
last_seq = 9387
current_ts = 282.837616
current_received_ts = 282.837097
current_seq = 9388
count = 365
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.837616
last_received_ts = 282.837097
last_seq = 9388
current_ts = 282.854248
current_received_ts = 282.853699
current_seq = 9389
count = 366
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.854248
last_received_ts = 282.853699
last_seq = 9389
current_ts = 282.870880
current_received_ts = 282.870331
current_seq = 9390
count = 367
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 10us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.870880
last_received_ts = 282.870331
last_seq = 9390
current_ts = 282.887512
current_received_ts = 282.887085
current_seq = 9391
count = 368
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.887512
last_received_ts = 282.887085
last_seq = 9391
current_ts = 282.904144
current_received_ts = 282.903717
current_seq = 9392
count = 369
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.904144
last_received_ts = 282.903717
last_seq = 9392
current_ts = 282.920776
current_received_ts = 282.920349
current_seq = 9393
count = 370
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.920776
last_received_ts = 282.920349
last_seq = 9393
current_ts = 282.937408
current_received_ts = 282.936981
current_seq = 9394
count = 371
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 33us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.937408
last_received_ts = 282.936981
last_seq = 9394
current_ts = 282.954041
current_received_ts = 282.953613
current_seq = 9395
count = 372
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 8us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.954041
last_received_ts = 282.953613
last_seq = 9395
current_ts = 282.970673
current_received_ts = 282.970245
current_seq = 9396
count = 373
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.970673
last_received_ts = 282.970245
last_seq = 9396
current_ts = 282.987305
current_received_ts = 282.986877
current_seq = 9397
count = 374
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 282.987305
last_received_ts = 282.986877
last_seq = 9397
current_ts = 283.003937
current_received_ts = 283.003510
current_seq = 9398
count = 375
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 283.003937
last_received_ts = 283.003510
last_seq = 9398
current_ts = 283.020569
current_received_ts = 283.020111
current_seq = 9399
count = 376
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 283.020569
last_received_ts = 283.020111
last_seq = 9399
current_ts = 283.037201
current_received_ts = 283.036774
current_seq = 9400
count = 377
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 283.037201
last_received_ts = 283.036774
last_seq = 9400
current_ts = 283.053833
current_received_ts = 283.053406
current_seq = 9401
count = 378
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 9us
(kms_flip:2547) DEBUG: name = flip
last_ts = 283.053833
last_received_ts = 283.053406
last_seq = 9401
current_ts = 283.070465
current_received_ts = 283.070038
current_seq = 9402
count = 379
seq_step = 1
(kms_flip:2547) DEBUG: Vblank took 633us
(kms_flip:2547) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:926:
(kms_flip:2547) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2547) igt_core-INFO: Stack trace:
(kms_flip:2547) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2547) igt_core-INFO:   #1 ../tests/kms_flip.c:921 run_test_on_crtc_set.constprop.0()
(kms_flip:2547) igt_core-INFO:   #2 ../tests/kms_flip.c:1887 run_test()
(kms_flip:2547) igt_core-INFO:   #3 ../tests/kms_flip.c:2120 __igt_unique____real_main2043()
(kms_flip:2547) igt_core-INFO:   #4 ../tests/kms_flip.c:2043 main()
(kms_flip:2547) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2547) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2547) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest B-HDMI-A1: FAIL (6.897s)
Subtest flip-vs-expired-vblank: FAIL (30.149s)
(kms_flip:2547) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2351:
(kms_flip:2547) igt_kms-CRITICAL: Failed assertion: found_it
(kms_flip:2547) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor
(kms_flip:2547) igt_kms-CRITICAL: DPMS property not found on 75
Dmesg
<6> [276.873401] [IGT] kms_flip: starting dynamic subtest B-HDMI-A1
<7> [276.873889] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:89]
<7> [276.874122] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:90]
<7> [276.926298] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:52:pipe A]
<7> [276.926519] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [276.927073] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [276.927572] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [276.928076] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in has_pch_encoder (expected yes, found no)
<7> [276.928598] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in fdi_lanes (expected 3, found 0)
<7> [276.929187] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in fdi_m_n (expected tu 64 data 6934582/8388608 link 288940/524288, found tu 0, data 0/0 link 0/0)
<7> [276.929629] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [276.930188] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [276.930453] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [276.930686] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [276.930989] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [276.931253] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [276.931486] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [276.931718] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [276.931951] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [276.932207] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [276.932453] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [276.932686] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [276.932918] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [276.933188] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [276.933444] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [276.933677] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [276.933909] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [276.934159] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [276.934412] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [276.934644] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [276.934876] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [276.935143] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [276.935421] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [276.935654] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [276.935885] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [276.936157] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [276.936405] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [276.936637] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [276.936868] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [276.937121] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [276.937373] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [276.937605] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [276.937839] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [276.938103] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148800, found 0)
<7> [276.938354] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148800, found 0)
<7> [276.938586] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in port_clock (expected 223200, found 0)
<7> [276.938818] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [276.939111] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [276.939349] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in avi infoframe
<7> [276.939606] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [276.939830] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [276.939833] i915 0000:00:02.0: colorspace: RGB
<7> [276.939835] i915 0000:00:02.0: scan mode: Underscan
<7> [276.939837] i915 0000:00:02.0: colorimetry: No Data
<7> [276.939839] i915 0000:00:02.0: picture aspect: 16:9
<7> [276.939841] i915 0000:00:02.0: active aspect: Same as Picture
<7> [276.939843] i915 0000:00:02.0: itc: No Data
<7> [276.939845] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [276.939847] i915 0000:00:02.0: quantization range: Default
<7> [276.939849] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [276.939851] i915 0000:00:02.0: video code: 16
<7> [276.939853] i915 0000:00:02.0: ycc quantization range: Full
<7> [276.939855] i915 0000:00:02.0: hdmi content type: Graphics
<7> [276.939857] i915 0000:00:02.0: pixel repeat: 0
<7> [276.939859] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [276.939861] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [276.940124] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in spd infoframe
<7> [276.940417] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [276.940642] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [276.940645] i915 0000:00:02.0: vendor: Intel
<7> [276.940647] i915 0000:00:02.0: product: Integrated gfx
<7> [276.940649] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [276.940651] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [276.940876] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hdmi infoframe
<7> [276.941150] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [276.941393] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [276.941396] i915 0000:00:02.0: empty frame
<7> [276.941398] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [276.941632] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:52:pipe A] fastset requirement not met, forcing full modeset
<7> [276.941864] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:52:pipe A] releasing PCH DPLL A
<7> [276.942167] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:52:pipe A] min cdclk: 165334 kHz -> 0 kHz
<7> [276.942414] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:52:pipe A] enable: no [modeset]
<7> [276.942640] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:primary A] fb: [NOFB], visible: no
<7> [276.942918] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:75:HDMI-A-1][ENCODER:74:HDMI D] Disable audio codec on [CRTC:52:pipe A]
<7> [276.971007] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [276.988252] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found
<7> [276.988728] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found
<7> [276.989220] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found
<7> [276.999291] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PCH DPLL A (active 0x1, on? 1) for [CRTC:52:pipe A]
<7> [276.999797] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PCH DPLL A
<7> [277.000576] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:73:CRT]
<7> [277.000852] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:HDMI D]
<7> [277.001146] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:82:DP D]
<7> [277.001467] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:75:HDMI-A-1]
<7> [277.001789] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:52:pipe A]
<7> [277.002254] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:71:pipe B]
<7> [277.002439] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:71:pipe B]
<7> [277.002469] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:75:HDMI-A-1]
<7> [277.002574] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [277.002866] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:71:pipe B] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [277.003146] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe B, lanes 3
<7> [277.003409] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.enable (expected no, found yes)
<7> [277.003643] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.active (expected no, found yes)
<7> [277.003876] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in cpu_transcoder (expected -1, found 1)
<7> [277.004128] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in has_pch_encoder (expected no, found yes)
<7> [277.004393] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in fdi_lanes (expected 0, found 3)
<7> [277.004625] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in fdi_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6934582/8388608 link 288940/524288)
<7> [277.004859] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in lane_count (expected 0, found 4)
<7> [277.005157] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [277.005435] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [277.005662] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [277.005897] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [277.006181] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [277.006455] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [277.006682] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [277.006907] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [277.007176] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [277.007473] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [277.007712] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [277.007944] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [277.008198] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [277.008453] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [277.008711] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [277.008943] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [277.009199] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [277.009471] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [277.009703] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [277.009935] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [277.010183] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [277.010456] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [277.010688] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [277.010920] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [277.011178] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [277.011450] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [277.011677] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [277.011903] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [277.012180] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [277.012450] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [277.012676] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [277.012915] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in dpll_hw_state
<7> [277.013164] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [277.013422] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [277.013648] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [277.013872] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0xc4010001, dpll_md: 0x0, fp0: 0x30f06, fp1: 0x30f06
<7> [277.014158] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [277.014472] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148800)
<7> [277.014699] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148800)
<7> [277.014934] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in port_clock (expected 0, found 223200)
<7> [277.015211] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [277.015482] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006)
<7> [277.015722] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in avi infoframe
<7> [277.015954] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [277.016207] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [277.016485] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [277.016488] i915 0000:00:02.0: colorspace: RGB
<7> [277.016490] i915 0000:00:02.0: scan mode: Underscan
<7> [277.016492] i915 0000:00:02.0: colorimetry: No Data
<7> [277.016494] i915 0000:00:02.0: picture aspect: 16:9
<7> [277.016496] i915 0000:00:02.0: active aspect: Same as Picture
<7> [277.016498] i915 0000:00:02.0: itc: No Data
<7> [277.016500] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [277.016502] i915 0000:00:02.0: quantization range: Default
<7> [277.016504] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [277.016506] i915 0000:00:02.0: video code: 16
<7> [277.016508] i915 0000:00:02.0: ycc quantization range: Full
<7> [277.016510] i915 0000:00:02.0: hdmi content type: Graphics
<7> [277.016512] i915 0000:00:02.0: pixel repeat: 0
<7> [277.016514] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [277.016517] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in spd infoframe
<7> [277.016750] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [277.017011] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [277.017285] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [277.017288] i915 0000:00:02.0: vendor: Intel
<7> [277.017290] i915 0000:00:02.0: product: Integrated gfx
<7> [277.017292] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [277.017294] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:71:pipe B] fastset requirement not met in hdmi infoframe
<7> [277.017527] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [277.017759] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [277.018005] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [277.018008] i915 0000:00:02.0: empty frame
<7> [277.018010] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:71:pipe B] fastset requirement not met, forcing full modeset
<7> [277.018305] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:71:pipe B] min cdclk: 0 kHz -> 165334 kHz
<7> [277.018539] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:71:pipe B] allocated PCH DPLL A
<7> [277.018769] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:71:pipe B] reserving PCH DPLL A
<7> [277.019021] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:71:pipe B] enable: yes [modeset]
<7> [277.019301] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [277.019533] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: B, pipe bpp: 36, dithering: 0
<7> [277.019764] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [277.020024] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [277.020027] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [277.020301] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [277.020549] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [277.020781] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] fdi: lanes: 3; data_m: 6934582, data_n: 8388608, link_m: 288940, link_n: 524288, tu: 64
<7> [277.021031] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [277.021318] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x6
<7> [277.021543] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [277.021546] i915 0000:00:02.0: colorspace: RGB
<7> [277.021556] i915 0000:00:02.0: scan mode: Underscan
<7> [277.021558] i915 0000:00:02.0: colorimetry: No Data
<7> [277.021560] i915 0000:00:02.0: picture aspect: 16:9
<7> [277.021562] i915 0000:00:02.0: active aspect: Same as Picture
<7> [277.021564] i915 0000:00:02.0: itc: No Data
<7> [277.021566] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [277.021568] i915 0000:00:02.0: quantization range: Default
<7> [277.021570] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [277.021572] i915 0000:00:02.0: video code: 16
<7> [277.021573] i915 0000:00:02.0: ycc quantization range: Full
<7> [277.021575] i915 0000:00:02.0: hdmi content type: Graphics
<7> [277.021577] i915 0000:00:02.0: pixel repeat: 0
<7> [277.021579] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [277.021582] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [277.021584] i915 0000:00:02.0: vendor: Intel
<7> [277.021586] i915 0000:00:02.0: product: Integrated gfx
<7> [277.021588] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [277.021590] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [277.021592] i915 0000:00:02.0: empty frame
<7> [277.021594] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 10 00 08 00 65 32 00 01 00 00 00 00 00 00 00 00
<7> [277.021826] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 61 a4 9a 00 4d 69 20 54 56 09 07 07 11 17 50 51
<7> [277.022091] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 07 00 00 00
<7> [277.022382] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1
<7> [277.022615] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [277.022847] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0
<7> [277.023100] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 0, vmax vblank: 0, vmin vtotal: 0, vmax vtotal: 0
<7> [277.023378] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [277.023610] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [277.023843] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [277.024114] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148800, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [277.024382] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 60 148800 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [277.024614] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148800, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [277.024846] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 223200, pipe src: 1920x1080+0+0, pixel rate 148800, min cdclk 165334
<7> [277.025103] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0
<7> [277.025365] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [277.025591] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [277.025815] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0xc4010001, dpll_md: 0x0, fp0: 0x30f06, fp1: 0x30f06
<7> [277.026103] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [277.026391] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [277.026617] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [277.026850] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [277.027117] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [277.027384] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [277.027608] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [277.027833] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:53:primary B] fb: [FB:89] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [277.028107] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [277.028403] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [277.030855] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [277.031135] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:73:CRT]
<7> [277.031356] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:HDMI D]
<7> [277.031606] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:82:DP D]
<7> [277.032455] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe B
<7> [277.033517] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100
<7> [277.033741] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done.
<7> [277.034665] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x600
<7> [277.034889] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done.
<7> [277.035146] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train done.
<7> [277.035435] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PCH DPLL A (active 0x2, on? 0) for [CRTC:71:pipe B]
<7> [277.035659] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PCH DPLL A
<7> [277.087450] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:75:HDMI-A-1][ENCODER:74:HDMI D] Enable audio codec on [CRTC:71:pipe B], 36 bytes ELD
<7> [277.104025] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] HDMI audio pixel clock setting for 148800 not found, falling back to defaults
<7> [277.104462] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)
<7> [277.104895] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:75:HDMI-A-1]
<7> [277.105385] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:71:pipe B]
<6> [283.771482] [IGT] kms_flip: finished subtest B-HDMI-A1, FAIL
<6> [283.772691] [IGT] kms_flip: finished subtest flip-vs-expired-vblank, FAIL
<7> [283.785606] i915 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:90] from all active usage due to RMFB ioctl
<7> [283.785953] i915 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:53:primary B] because [FB:90] is removed
<6> [283.791681] [IGT] kms_flip: exiting, ret=98
<7> [283.809870] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:72:VGA-1] Limiting target display pipe bpp to 24 (EDID bpp 0, max requested bpp 0, max platform bpp 36)
<7> [283.810153] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:52:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [283.810583] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe A, lanes 1
<7> [283.810809] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [283.811038] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [283.811322] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [283.811593] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in has_pch_encoder (expected no, found yes)
<7> [283.811820] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in fdi_lanes (expected 0, found 1)
<7> [283.812047] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in fdi_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6039797/8388608 link 125829/524288)
<7> [283.812307] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000002)
<7> [283.812596] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [283.812823] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1024)
<7> [283.813050] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 1344)
<7> [283.813313] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1024)
<7> [283.813581] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 1344)
<7> [283.813808] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1048)
<7> [283.814035] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 1184)
<7> [283.815178] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 768)
<7> [283.815436] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 768)
<7> [283.815709] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 771)
<7> [283.815937] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 777)
<7> [283.816172] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 806)
<7> [283.816427] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 806)
<7> [283.816711] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1024)
<7> [283.816938] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 1344)
<7> [283.817165] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1024)
<7> [283.817442] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 1344)
<7> [283.817733] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1048)
<7> [283.817961] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 1184)
<7> [283.818396] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 768)
<7> [283.818625] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 768)
<7> [283.818852] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 771)
<7> [283.819079] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 777)
<7> [283.819510] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 806)
<7> [283.819745] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 806)
<7> [283.819972] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [283.820229] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [283.820502] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [283.820745] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in dpll_hw_state
<7> [283.820978] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [283.821232] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0xc4010001, dpll_md: 0x0, fp0: 0x30f06, fp1: 0x30f06
<7> [283.821505] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [283.821732] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [283.821959] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [283.822229] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 64800)
<7> [283.822496] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 64800)
<7> [283.822722] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in port_clock (expected 0, found 64800)
<7> [283.822950] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:52:pipe A] fastset requirement not met, forcing full modeset
<7> [283.823271] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:52:pipe A] min cdclk: 0 kHz -> 72000 kHz
<7> [283.823525] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:52:pipe A] allocated PCH DPLL B
<7> [283.823749] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:52:pipe A] reserving PCH DPLL B
<7> [283.823978] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:52:pipe A] enable: yes [modeset]
<7> [283.824247] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB
<7> [283.824522] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [283.824748] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [283.824972] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [283.825233] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [283.825521] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [283.825746] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] fdi: lanes: 1; data_m: 6039797, data_n: 8388608, link_m: 125829, link_n: 524288, tu: 64
<7> [283.825972] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [283.826229] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1
<7> [283.826503] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [283.826728] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0
<7> [283.826954] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 0, vmax vblank: 0, vmin vtotal: 0, vmax vtotal: 0
<7> [283.827223] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [283.827493] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [283.827720] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [283.827946] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=64800, hd=1024 hb=1024-1344 hs=1048-1184 ht=1344, vd=768 vb=768-806 vs=771-777 vt=806, flags=0xa
<7> [283.828181] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1024x768": 60 64800 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [283.828542] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=64800, hd=1024 hb=1024-1344 hs=1048-1184 ht=1344, vd=768 vb=768-806 vs=771-777 vt=806, flags=0xa
<7> [283.828792] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 64800, pipe src: 1024x768+0+0, pixel rate 64800, min cdclk 72000
<7> [283.829018] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0
<7> [283.829281] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [283.829546] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [283.829771] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [283.829997] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [283.830268] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [283.830545] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [283.830771] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [283.830996] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [283.831272] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [283.831539] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [283.831765] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:primary A] fb: [FB:88] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [283.831992] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [283.832335] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0
<7> [283.832591] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:39:sprite A] fb: [NOFB], visible: no
<7> [283.832817] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:46:cursor A] fb: [NOFB], visible: no
<7> [283.833123] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:73:CRT]
<7> [283.833468] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:HDMI D]
<7> [283.833703] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:82:DP D]
<7> [283.834493] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [283.835558] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100
<7> [283.835781] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done.
<7> [283.836663] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x600
<7> [283.836886] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done.
<7> [283.837105] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train done.
<7> [283.837374] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PCH DPLL B (active 0x1, on? 0) for [CRTC:52:pipe A]
<7> [283.837608] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PCH DPLL B
<7> [283.889659] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:72:VGA-1]
<7> [283.889935] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:52:pipe A]
<7> [283.890345] i915 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0
Created at 2026-03-12 03:14:54