Results for igt@kms_flip@2x-flip-vs-expired-vblank@ac-hdmi-a1-hdmi-a2

Result: Fail

integration-manifest git-log-oneline i915_display_info22 igt_runner22 runtimes22 results22.json results22-i915-load.json guc_logs22.tar i915_display_info_post_exec22 boot22 dmesg22

DetailValue
Duration 2.87 seconds
Hostname 
shard-glk4
Igt-Version 
IGT-Version: 2.4-g0a8f2f8f5 (x86_64) (Linux: 7.0.0-rc7-CI_DRM_18284-g5f8792621868+ x86_64)
Out 
Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9 
  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:1976 run_pair()
  #3 ../tests/kms_flip.c:2135 __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 AC-HDMI-A1-HDMI-A2: FAIL (2.866s)
Err 
Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
(kms_flip:2358) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:930:
(kms_flip:2358) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest AC-HDMI-A1-HDMI-A2 failed.
**** DEBUG ****
(kms_flip:2358) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:2358) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=7680)
(kms_flip:2358) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:2358) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2358) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2358) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS
(kms_flip:2358) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2358) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2358) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS
(kms_flip:2358) igt_kms-INFO:   1920x1080: 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9 
(kms_flip:2358) igt_kms-INFO:   1920x1080: 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9 
(kms_flip:2358) DEBUG: No stale events found
(kms_flip:2358) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:2358) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:2358) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:2358) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:2358) DEBUG: name = flip
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 210.184998
current_received_ts = 210.184402
current_seq = 6259
count = 0
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 8us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.184998
last_received_ts = 210.184402
last_seq = 6259
current_ts = 210.201675
current_received_ts = 210.202103
current_seq = 6260
count = 1
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 6us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.201675
last_received_ts = 210.202103
last_seq = 6260
current_ts = 210.218338
current_received_ts = 210.217728
current_seq = 6261
count = 2
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 6us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.218338
last_received_ts = 210.217728
last_seq = 6261
current_ts = 210.235001
current_received_ts = 210.235077
current_seq = 6262
count = 3
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 6us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.235001
last_received_ts = 210.235077
last_seq = 6262
current_ts = 210.251663
current_received_ts = 210.251083
current_seq = 6263
count = 4
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 6us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.251663
last_received_ts = 210.251083
last_seq = 6263
current_ts = 210.268341
current_received_ts = 210.267746
current_seq = 6264
count = 5
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 6us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.268341
last_received_ts = 210.267746
last_seq = 6264
current_ts = 210.285004
current_received_ts = 210.284409
current_seq = 6265
count = 6
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 6us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.285004
last_received_ts = 210.284409
last_seq = 6265
current_ts = 210.301666
current_received_ts = 210.301086
current_seq = 6266
count = 7
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 6us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.301666
last_received_ts = 210.301086
last_seq = 6266
current_ts = 210.318329
current_received_ts = 210.317734
current_seq = 6267
count = 8
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 6us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.318329
last_received_ts = 210.317734
last_seq = 6267
current_ts = 210.335007
current_received_ts = 210.334396
current_seq = 6268
count = 9
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 6us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.335007
last_received_ts = 210.334396
last_seq = 6268
current_ts = 210.351669
current_received_ts = 210.351547
current_seq = 6269
count = 10
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 18us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.351669
last_received_ts = 210.351547
last_seq = 6269
current_ts = 210.368347
current_received_ts = 210.368195
current_seq = 6270
count = 11
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 21us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.368347
last_received_ts = 210.368195
last_seq = 6270
current_ts = 210.384995
current_received_ts = 210.384613
current_seq = 6271
count = 12
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 22us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.384995
last_received_ts = 210.384613
last_seq = 6271
current_ts = 210.401672
current_received_ts = 210.401505
current_seq = 6272
count = 13
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 22us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.401672
last_received_ts = 210.401505
last_seq = 6272
current_ts = 210.418350
current_received_ts = 210.418213
current_seq = 6273
count = 14
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 23us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.418350
last_received_ts = 210.418213
last_seq = 6273
current_ts = 210.434998
current_received_ts = 210.434616
current_seq = 6274
count = 15
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 24us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.434998
last_received_ts = 210.434616
last_seq = 6274
current_ts = 210.451675
current_received_ts = 210.451508
current_seq = 6275
count = 16
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 21us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.451675
last_received_ts = 210.451508
last_seq = 6275
current_ts = 210.468338
current_received_ts = 210.468201
current_seq = 6276
count = 17
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 22us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.468338
last_received_ts = 210.468201
last_seq = 6276
current_ts = 210.485001
current_received_ts = 210.484619
current_seq = 6277
count = 18
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 23us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.485001
last_received_ts = 210.484619
last_seq = 6277
current_ts = 210.501678
current_received_ts = 210.501526
current_seq = 6278
count = 19
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 25us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.501678
last_received_ts = 210.501526
last_seq = 6278
current_ts = 210.518326
current_received_ts = 210.518204
current_seq = 6279
count = 20
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 24us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.518326
last_received_ts = 210.518204
last_seq = 6279
current_ts = 210.535019
current_received_ts = 210.534607
current_seq = 6280
count = 21
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 22us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.535019
last_received_ts = 210.534607
last_seq = 6280
current_ts = 210.551682
current_received_ts = 210.551498
current_seq = 6281
count = 22
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 24us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.551682
last_received_ts = 210.551498
last_seq = 6281
current_ts = 210.568344
current_received_ts = 210.568207
current_seq = 6282
count = 23
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 23us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.568344
last_received_ts = 210.568207
last_seq = 6282
current_ts = 210.585007
current_received_ts = 210.584610
current_seq = 6283
count = 24
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 25us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.585007
last_received_ts = 210.584610
last_seq = 6283
current_ts = 210.601685
current_received_ts = 210.601517
current_seq = 6284
count = 25
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 22us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.601685
last_received_ts = 210.601517
last_seq = 6284
current_ts = 210.618332
current_received_ts = 210.618195
current_seq = 6285
count = 26
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 19us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.618332
last_received_ts = 210.618195
last_seq = 6285
current_ts = 210.635010
current_received_ts = 210.634644
current_seq = 6286
count = 27
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 24us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.635010
last_received_ts = 210.634644
last_seq = 6286
current_ts = 210.651672
current_received_ts = 210.651520
current_seq = 6287
count = 28
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 24us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.651672
last_received_ts = 210.651520
last_seq = 6287
current_ts = 210.668335
current_received_ts = 210.668213
current_seq = 6288
count = 29
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 24us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.668335
last_received_ts = 210.668213
last_seq = 6288
current_ts = 210.685013
current_received_ts = 210.684662
current_seq = 6289
count = 30
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 24us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.685013
last_received_ts = 210.684662
last_seq = 6289
current_ts = 210.701675
current_received_ts = 210.701492
current_seq = 6290
count = 31
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 25us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.701675
last_received_ts = 210.701492
last_seq = 6290
current_ts = 210.718338
current_received_ts = 210.718216
current_seq = 6291
count = 32
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 23us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.718338
last_received_ts = 210.718216
last_seq = 6291
current_ts = 210.735001
current_received_ts = 210.734619
current_seq = 6292
count = 33
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 25us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.735001
last_received_ts = 210.734619
last_seq = 6292
current_ts = 210.751678
current_received_ts = 210.751495
current_seq = 6293
count = 34
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 21us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.751678
last_received_ts = 210.751495
last_seq = 6293
current_ts = 210.768356
current_received_ts = 210.768188
current_seq = 6294
count = 35
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 23us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.768356
last_received_ts = 210.768188
last_seq = 6294
current_ts = 210.785019
current_received_ts = 210.784607
current_seq = 6295
count = 36
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 21us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.785019
last_received_ts = 210.784607
last_seq = 6295
current_ts = 210.801682
current_received_ts = 210.801498
current_seq = 6296
count = 37
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 21us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.801682
last_received_ts = 210.801498
last_seq = 6296
current_ts = 210.818344
current_received_ts = 210.818192
current_seq = 6297
count = 38
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 20us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.818344
last_received_ts = 210.818192
last_seq = 6297
current_ts = 210.835007
current_received_ts = 210.834686
current_seq = 6298
count = 39
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 24us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.835007
last_received_ts = 210.834686
last_seq = 6298
current_ts = 210.851685
current_received_ts = 210.851517
current_seq = 6299
count = 40
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 24us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.851685
last_received_ts = 210.851517
last_seq = 6299
current_ts = 210.868347
current_received_ts = 210.868179
current_seq = 6300
count = 41
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 22us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.868347
last_received_ts = 210.868179
last_seq = 6300
current_ts = 210.885025
current_received_ts = 210.884613
current_seq = 6301
count = 42
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 22us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.885025
last_received_ts = 210.884613
last_seq = 6301
current_ts = 210.901688
current_received_ts = 210.901535
current_seq = 6302
count = 43
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 24us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.901688
last_received_ts = 210.901535
last_seq = 6302
current_ts = 210.918335
current_received_ts = 210.918259
current_seq = 6303
count = 44
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 24us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.918335
last_received_ts = 210.918259
last_seq = 6303
current_ts = 210.935013
current_received_ts = 210.934616
current_seq = 6304
count = 45
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 22us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.935013
last_received_ts = 210.934616
last_seq = 6304
current_ts = 210.951675
current_received_ts = 210.951508
current_seq = 6305
count = 46
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 23us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.951675
last_received_ts = 210.951508
last_seq = 6305
current_ts = 210.968338
current_received_ts = 210.968231
current_seq = 6306
count = 47
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 24us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.968338
last_received_ts = 210.968231
last_seq = 6306
current_ts = 210.985016
current_received_ts = 210.984604
current_seq = 6307
count = 48
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 22us
(kms_flip:2358) DEBUG: name = flip
last_ts = 210.985016
last_received_ts = 210.984604
last_seq = 6307
current_ts = 211.001678
current_received_ts = 211.001755
current_seq = 6308
count = 49
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 21us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.001678
last_received_ts = 211.001755
last_seq = 6308
current_ts = 211.018356
current_received_ts = 211.018204
current_seq = 6309
count = 50
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 23us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.018356
last_received_ts = 211.018204
last_seq = 6309
current_ts = 211.035004
current_received_ts = 211.034622
current_seq = 6310
count = 51
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 23us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.035004
last_received_ts = 211.034622
last_seq = 6310
current_ts = 211.051682
current_received_ts = 211.051514
current_seq = 6311
count = 52
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 21us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.051682
last_received_ts = 211.051514
last_seq = 6311
current_ts = 211.068359
current_received_ts = 211.068192
current_seq = 6312
count = 53
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 22us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.068359
last_received_ts = 211.068192
last_seq = 6312
current_ts = 211.085022
current_received_ts = 211.084549
current_seq = 6313
count = 54
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 21us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.085022
last_received_ts = 211.084549
last_seq = 6313
current_ts = 211.101685
current_received_ts = 211.101517
current_seq = 6314
count = 55
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 23us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.101685
last_received_ts = 211.101517
last_seq = 6314
current_ts = 211.118362
current_received_ts = 211.118210
current_seq = 6315
count = 56
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 24us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.118362
last_received_ts = 211.118210
last_seq = 6315
current_ts = 211.135010
current_received_ts = 211.134628
current_seq = 6316
count = 57
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 24us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.135010
last_received_ts = 211.134628
last_seq = 6316
current_ts = 211.151688
current_received_ts = 211.151505
current_seq = 6317
count = 58
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 26us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.151688
last_received_ts = 211.151505
last_seq = 6317
current_ts = 211.168350
current_received_ts = 211.168198
current_seq = 6318
count = 59
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 19us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.168350
last_received_ts = 211.168198
last_seq = 6318
current_ts = 211.185013
current_received_ts = 211.184647
current_seq = 6319
count = 60
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 23us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.185013
last_received_ts = 211.184647
last_seq = 6319
current_ts = 211.201691
current_received_ts = 211.201508
current_seq = 6320
count = 61
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 21us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.201691
last_received_ts = 211.201508
last_seq = 6320
current_ts = 211.218353
current_received_ts = 211.218216
current_seq = 6321
count = 62
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 26us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.218353
last_received_ts = 211.218216
last_seq = 6321
current_ts = 211.235016
current_received_ts = 211.234665
current_seq = 6322
count = 63
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 25us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.235016
last_received_ts = 211.234665
last_seq = 6322
current_ts = 211.251694
current_received_ts = 211.251556
current_seq = 6323
count = 64
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 23us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.251694
last_received_ts = 211.251556
last_seq = 6323
current_ts = 211.268356
current_received_ts = 211.268234
current_seq = 6324
count = 65
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 23us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.268356
last_received_ts = 211.268234
last_seq = 6324
current_ts = 211.285019
current_received_ts = 211.284683
current_seq = 6325
count = 66
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 24us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.285019
last_received_ts = 211.284683
last_seq = 6325
current_ts = 211.301682
current_received_ts = 211.301529
current_seq = 6326
count = 67
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 24us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.301682
last_received_ts = 211.301529
last_seq = 6326
current_ts = 211.318359
current_received_ts = 211.318237
current_seq = 6327
count = 68
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 23us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.318359
last_received_ts = 211.318237
last_seq = 6327
current_ts = 211.335022
current_received_ts = 211.334641
current_seq = 6328
count = 69
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 21us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.335022
last_received_ts = 211.334641
last_seq = 6328
current_ts = 211.351700
current_received_ts = 211.351517
current_seq = 6329
count = 70
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 20us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.351700
last_received_ts = 211.351517
last_seq = 6329
current_ts = 211.368362
current_received_ts = 211.368240
current_seq = 6330
count = 71
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 23us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.368362
last_received_ts = 211.368240
last_seq = 6330
current_ts = 211.385025
current_received_ts = 211.384644
current_seq = 6331
count = 72
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 25us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.385025
last_received_ts = 211.384644
last_seq = 6331
current_ts = 211.401688
current_received_ts = 211.401566
current_seq = 6332
count = 73
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 25us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.401688
last_received_ts = 211.401566
last_seq = 6332
current_ts = 211.418365
current_received_ts = 211.418213
current_seq = 6333
count = 74
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 25us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.418365
last_received_ts = 211.418213
last_seq = 6333
current_ts = 211.435028
current_received_ts = 211.434708
current_seq = 6334
count = 75
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 24us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.435028
last_received_ts = 211.434708
last_seq = 6334
current_ts = 211.451691
current_received_ts = 211.451523
current_seq = 6335
count = 76
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 20us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.451691
last_received_ts = 211.451523
last_seq = 6335
current_ts = 211.468353
current_received_ts = 211.468201
current_seq = 6336
count = 77
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 21us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.468353
last_received_ts = 211.468201
last_seq = 6336
current_ts = 211.485016
current_received_ts = 211.484711
current_seq = 6337
count = 78
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 25us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.485016
last_received_ts = 211.484711
last_seq = 6337
current_ts = 211.501694
current_received_ts = 211.501511
current_seq = 6338
count = 79
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 20us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.501694
last_received_ts = 211.501511
last_seq = 6338
current_ts = 211.518356
current_received_ts = 211.518234
current_seq = 6339
count = 80
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 25us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.518356
last_received_ts = 211.518234
last_seq = 6339
current_ts = 211.535019
current_received_ts = 211.534668
current_seq = 6340
count = 81
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 24us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.535019
last_received_ts = 211.534668
last_seq = 6340
current_ts = 211.551697
current_received_ts = 211.551559
current_seq = 6341
count = 82
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 24us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.551697
last_received_ts = 211.551559
last_seq = 6341
current_ts = 211.568359
current_received_ts = 211.568222
current_seq = 6342
count = 83
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 21us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.568359
last_received_ts = 211.568222
last_seq = 6342
current_ts = 211.585022
current_received_ts = 211.584717
current_seq = 6343
count = 84
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 24us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.585022
last_received_ts = 211.584717
last_seq = 6343
current_ts = 211.601685
current_received_ts = 211.601547
current_seq = 6344
count = 85
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 23us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.601685
last_received_ts = 211.601547
last_seq = 6344
current_ts = 211.618347
current_received_ts = 211.618225
current_seq = 6345
count = 86
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 20us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.618347
last_received_ts = 211.618225
last_seq = 6345
current_ts = 211.635025
current_received_ts = 211.634720
current_seq = 6346
count = 87
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 25us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.635025
last_received_ts = 211.634720
last_seq = 6346
current_ts = 211.651688
current_received_ts = 211.651520
current_seq = 6347
count = 88
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 19us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.651688
last_received_ts = 211.651520
last_seq = 6347
current_ts = 211.668365
current_received_ts = 211.668228
current_seq = 6348
count = 89
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 19us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.668365
last_received_ts = 211.668228
last_seq = 6348
current_ts = 211.685028
current_received_ts = 211.684708
current_seq = 6349
count = 90
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 26us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.685028
last_received_ts = 211.684708
last_seq = 6349
current_ts = 211.701691
current_received_ts = 211.701553
current_seq = 6350
count = 91
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 22us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.701691
last_received_ts = 211.701553
last_seq = 6350
current_ts = 211.718369
current_received_ts = 211.718216
current_seq = 6351
count = 92
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 19us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.718369
last_received_ts = 211.718216
last_seq = 6351
current_ts = 211.735031
current_received_ts = 211.734711
current_seq = 6352
count = 93
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 25us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.735031
last_received_ts = 211.734711
last_seq = 6352
current_ts = 211.751694
current_received_ts = 211.751556
current_seq = 6353
count = 94
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 23us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.751694
last_received_ts = 211.751556
last_seq = 6353
current_ts = 211.768372
current_received_ts = 211.768204
current_seq = 6354
count = 95
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 20us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.768372
last_received_ts = 211.768204
last_seq = 6354
current_ts = 211.785019
current_received_ts = 211.784683
current_seq = 6355
count = 96
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 25us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.785019
last_received_ts = 211.784683
last_seq = 6355
current_ts = 211.801697
current_received_ts = 211.801559
current_seq = 6356
count = 97
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 24us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.801697
last_received_ts = 211.801559
last_seq = 6356
current_ts = 211.818359
current_received_ts = 211.818222
current_seq = 6357
count = 98
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 24us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.818359
last_received_ts = 211.818222
last_seq = 6357
current_ts = 211.835022
current_received_ts = 211.834686
current_seq = 6358
count = 99
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 25us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.835022
last_received_ts = 211.834686
last_seq = 6358
current_ts = 211.851700
current_received_ts = 211.851532
current_seq = 6359
count = 100
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 21us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.851700
last_received_ts = 211.851532
last_seq = 6359
current_ts = 211.868362
current_received_ts = 211.868256
current_seq = 6360
count = 101
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 23us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.868362
last_received_ts = 211.868256
last_seq = 6360
current_ts = 211.885025
current_received_ts = 211.884720
current_seq = 6361
count = 102
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 25us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.885025
last_received_ts = 211.884720
last_seq = 6361
current_ts = 211.901688
current_received_ts = 211.901535
current_seq = 6362
count = 103
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 25us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.901688
last_received_ts = 211.901535
last_seq = 6362
current_ts = 211.918365
current_received_ts = 211.918228
current_seq = 6363
count = 104
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 23us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.918365
last_received_ts = 211.918228
last_seq = 6363
current_ts = 211.935028
current_received_ts = 211.934723
current_seq = 6364
count = 105
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 25us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.935028
last_received_ts = 211.934723
last_seq = 6364
current_ts = 211.951706
current_received_ts = 211.951538
current_seq = 6365
count = 106
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 25us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.951706
last_received_ts = 211.951538
last_seq = 6365
current_ts = 211.968353
current_received_ts = 211.968246
current_seq = 6366
count = 107
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 23us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.968353
last_received_ts = 211.968246
last_seq = 6366
current_ts = 211.985031
current_received_ts = 211.984711
current_seq = 6367
count = 108
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 25us
(kms_flip:2358) DEBUG: name = flip
last_ts = 211.985031
last_received_ts = 211.984711
last_seq = 6367
current_ts = 212.001709
current_received_ts = 212.001556
current_seq = 6368
count = 109
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 25us
(kms_flip:2358) DEBUG: name = flip
last_ts = 212.001709
last_received_ts = 212.001556
last_seq = 6368
current_ts = 212.018372
current_received_ts = 212.018219
current_seq = 6369
count = 110
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 21us
(kms_flip:2358) DEBUG: name = flip
last_ts = 212.018372
last_received_ts = 212.018219
last_seq = 6369
current_ts = 212.035034
current_received_ts = 212.034683
current_seq = 6370
count = 111
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 24us
(kms_flip:2358) DEBUG: name = flip
last_ts = 212.035034
last_received_ts = 212.034683
last_seq = 6370
current_ts = 212.051697
current_received_ts = 212.051468
current_seq = 6371
count = 112
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 21us
(kms_flip:2358) DEBUG: name = flip
last_ts = 212.051697
last_received_ts = 212.051468
last_seq = 6371
current_ts = 212.068375
current_received_ts = 212.068115
current_seq = 6372
count = 113
seq_step = 1
(kms_flip:2358) DEBUG: Vblank took 1013us
(kms_flip:2358) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:930:
(kms_flip:2358) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2358) igt_core-INFO: Stack trace:
(kms_flip:2358) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2358) igt_core-INFO:   #1 ../tests/kms_flip.c:925 run_test_on_crtc_set.constprop.0()
(kms_flip:2358) igt_core-INFO:   #2 ../tests/kms_flip.c:1976 run_pair()
(kms_flip:2358) igt_core-INFO:   #3 ../tests/kms_flip.c:2135 __igt_unique____real_main2047()
(kms_flip:2358) igt_core-INFO:   #4 ../tests/kms_flip.c:2047 main()
(kms_flip:2358) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2358) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2358) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest AC-HDMI-A1-HDMI-A2: FAIL (2.866s)
Dmesg
<6> [209.292177] [IGT] kms_flip: starting dynamic subtest AC-HDMI-A1-HDMI-A2
<7> [209.293558] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:184]
<7> [209.293904] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:185]
<7> [209.349750] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:78:pipe A]
<7> [209.350252] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [209.351411] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [209.352464] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [209.353314] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [209.353867] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in lane_lat_optim_mask (expected 0x0000000d, found 0x00000000)
<7> [209.354522] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [209.354979] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [209.355385] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [209.355764] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [209.356141] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [209.356543] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [209.356922] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [209.357337] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [209.357716] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [209.358093] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [209.358580] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [209.358961] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [209.359592] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [209.360058] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [209.360564] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [209.360966] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [209.361400] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [209.361801] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [209.362180] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [209.362615] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [209.363022] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [209.363479] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [209.363860] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [209.364240] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [209.364700] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [209.365084] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [209.365521] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [209.365906] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [209.366309] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [209.366689] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [209.367069] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [209.367477] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [209.367934] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [209.368359] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [209.368741] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in port_clock (expected 222750, found 0)
<7> [209.369121] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [209.369552] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [209.369932] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in avi infoframe
<7> [209.370586] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [209.371050] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [209.371056] i915 0000:00:02.0: colorspace: RGB
<7> [209.371060] i915 0000:00:02.0: scan mode: Underscan
<7> [209.371063] i915 0000:00:02.0: colorimetry: No Data
<7> [209.371067] i915 0000:00:02.0: picture aspect: No Data
<7> [209.371070] i915 0000:00:02.0: active aspect: Same as Picture
<7> [209.371074] i915 0000:00:02.0: itc: No Data
<7> [209.371077] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [209.371081] i915 0000:00:02.0: quantization range: Full
<7> [209.371084] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [209.371087] i915 0000:00:02.0: video code: 0
<7> [209.371091] i915 0000:00:02.0: ycc quantization range: Full
<7> [209.371095] i915 0000:00:02.0: hdmi content type: Graphics
<7> [209.371098] i915 0000:00:02.0: pixel repeat: 0
<7> [209.371101] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [209.371105] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [209.371624] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in spd infoframe
<7> [209.372035] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [209.372463] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [209.372468] i915 0000:00:02.0: vendor: Intel
<7> [209.372471] i915 0000:00:02.0: product: Integrated gfx
<7> [209.372475] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [209.372478] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [209.372879] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hdmi infoframe
<7> [209.373257] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [209.373679] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [209.373684] i915 0000:00:02.0: empty frame
<7> [209.373687] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [209.374100] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:78:pipe A] fastset requirement not met, forcing full modeset
<7> [209.374606] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:78:pipe A] releasing PORT PLL B
<7> [209.375048] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:78:pipe A] dbuf slices 0x1 -> 0x0, ddb (0 - 510) -> (0 - 0), active pipes 0x3 -> 0x2
<7> [209.375493] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:121:pipe B] dbuf slices 0x1 -> 0x1, ddb (510 - 1020) -> (0 - 1020), active pipes 0x3 -> 0x2
<7> [209.375953] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:72:cursor A] ddb ( 497 - 510) -> ( 0 - 0), size 13 -> 0
<7> [209.376378] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:115:cursor B] ddb (1007 - 1020) -> ( 988 - 1020), size 13 -> 32
<7> [209.376847] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:78:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [209.377249] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:78:pipe A] enable: no [modeset]
<7> [209.377694] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:36:plane 1A] fb: [NOFB], visible: no
<7> [209.378076] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:45:plane 2A] fb: [NOFB], visible: no
<7> [209.378481] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:54:plane 3A] fb: [NOFB], visible: no
<7> [209.378861] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:63:plane 4A] fb: [NOFB], visible: no
<7> [209.379237] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:72:cursor A] fb: [NOFB], visible: no
<7> [209.379780] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:166:HDMI-A-1][ENCODER:165:DDI B/PHY B] Disable audio codec on [CRTC:78:pipe A]
<7> [209.401175] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B
<7> [209.401991] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [209.413867] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x10
<7> [209.419336] i915 0000:00:02.0: [drm:intel_ddi_disable_transcoder_func [i915]] Quirk Increase DDI disabled time
<7> [209.521766] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [209.526252] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PORT PLL B (active 0x1, on? 1) for [CRTC:78:pipe A]
<7> [209.527002] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PORT PLL B
<7> [209.527637] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:165:DDI B/PHY B]
<7> [209.528065] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:175:DDI C/PHY C]
<7> [209.528540] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:166:HDMI-A-1]
<7> [209.546518] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling dpio-common-b
<7> [209.547783] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:78:pipe A]
<7> [209.549845] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:121:pipe B]
<7> [209.550099] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [209.551176] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [209.551719] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [209.552105] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [209.552602] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in lane_lat_optim_mask (expected 0x0000000d, found 0x00000000)
<7> [209.552989] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [209.553504] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [209.553890] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [209.554404] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [209.554870] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [209.555253] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [209.555902] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [209.556555] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [209.556968] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [209.557502] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [209.557887] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [209.558409] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [209.558809] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [209.559188] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [209.559709] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [209.560097] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [209.560565] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [209.560947] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [209.561451] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [209.561833] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [209.562211] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [209.562695] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [209.563159] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [209.564032] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [209.564730] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [209.565154] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [209.565737] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [209.566124] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [209.566647] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [209.567050] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [209.567564] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [209.567957] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [209.568483] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [209.568866] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [209.569244] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in port_clock (expected 222750, found 0)
<7> [209.569760] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [209.570143] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [209.570830] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in avi infoframe
<7> [209.571222] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [209.571956] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [209.571962] i915 0000:00:02.0: colorspace: RGB
<7> [209.571966] i915 0000:00:02.0: scan mode: Underscan
<7> [209.571969] i915 0000:00:02.0: colorimetry: No Data
<7> [209.571973] i915 0000:00:02.0: picture aspect: No Data
<7> [209.571976] i915 0000:00:02.0: active aspect: Same as Picture
<7> [209.571979] i915 0000:00:02.0: itc: No Data
<7> [209.571982] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [209.571986] i915 0000:00:02.0: quantization range: Full
<7> [209.571989] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [209.571992] i915 0000:00:02.0: video code: 0
<7> [209.571995] i915 0000:00:02.0: ycc quantization range: Full
<7> [209.571999] i915 0000:00:02.0: hdmi content type: Graphics
<7> [209.572002] i915 0000:00:02.0: pixel repeat: 0
<7> [209.572005] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [209.572009] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [209.572750] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in spd infoframe
<7> [209.573167] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [209.573727] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [209.573732] i915 0000:00:02.0: vendor: Intel
<7> [209.573736] i915 0000:00:02.0: product: Integrated gfx
<7> [209.573740] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [209.573743] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [209.574127] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hdmi infoframe
<7> [209.574682] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [209.575088] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [209.575092] i915 0000:00:02.0: empty frame
<7> [209.575096] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [209.575635] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:121:pipe B] fastset requirement not met, forcing full modeset
<7> [209.576024] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:121:pipe B] releasing PORT PLL C
<7> [209.576606] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:121:pipe B] dbuf slices 0x1 -> 0x0, ddb (0 - 1020) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [209.577064] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:115:cursor B] ddb ( 988 - 1020) -> ( 0 - 0), size 32 -> 0
<7> [209.577632] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:121:pipe B] min cdclk: 192000 kHz -> 0 kHz
<7> [209.578070] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [209.578536] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 79200 kHz, actual 79200 kHz
<7> [209.578915] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 4, actual 4
<7> [209.579384] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:121:pipe B] enable: no [modeset]
<7> [209.579769] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:79:plane 1B] fb: [NOFB], visible: no
<7> [209.580230] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:88:plane 2B] fb: [NOFB], visible: no
<7> [209.581112] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:97:plane 3B] fb: [NOFB], visible: no
<7> [209.581806] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:106:plane 4B] fb: [NOFB], visible: no
<7> [209.582218] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:115:cursor B] fb: [NOFB], visible: no
<7> [209.582950] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:176:HDMI-A-2][ENCODER:175:DDI C/PHY C] Disable audio codec on [CRTC:121:pipe B]
<7> [209.612730] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port C
<7> [209.614524] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe B
<7> [209.630336] i915 0000:00:02.0: [drm:intel_ddi_disable_transcoder_func [i915]] Quirk Increase DDI disabled time
<7> [209.737760] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_C
<7> [209.738981] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PORT PLL C (active 0x2, on? 1) for [CRTC:121:pipe B]
<7> [209.740641] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PORT PLL C
<7> [209.741049] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 79200 kHz, VCO 633600 kHz, ref 19200 kHz, bypass 19200 kHz, voltage level 4
<7> [209.741810] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:165:DDI B/PHY B]
<7> [209.742498] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:175:DDI C/PHY C]
<7> [209.742940] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:176:HDMI-A-2]
<7> [209.743726] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling dpio-common-c
<7> [209.744176] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:121:pipe B]
<7> [209.745319] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [209.745559] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:164:pipe C]
<7> [209.745748] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 316800 kHz, actual 316800 kHz
<7> [209.746115] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 13, actual 13
<7> [209.746672] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 316800 kHz, VCO 633600 kHz, ref 19200 kHz, bypass 19200 kHz, voltage level 13
<7> [209.747637] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:164:pipe C]
<7> [209.747702] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:176:HDMI-A-2]
<7> [209.747884] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [209.748405] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:164:pipe C] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [209.748890] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:175:DDI C/PHY C] [CRTC:164:pipe C]
<7> [209.749323] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.enable (expected no, found yes)
<7> [209.749707] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.active (expected no, found yes)
<7> [209.750086] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2)
<7> [209.750527] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in lane_count (expected 0, found 4)
<7> [209.750935] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in lane_lat_optim_mask (expected 0x00000000, found 0x0000000d)
<7> [209.751296] azx_init_pci: snd_hda_intel 0000:00:0e.0: Clearing TCSEL
<7> [209.751314] azx_init_pci: snd_hda_intel 0000:00:0e.0: SCH snoop: Enabled
<7> [209.751358] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [209.751765] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [209.752142] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [209.752613] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [209.752994] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [209.753425] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [209.753807] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [209.754186] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [209.754600] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [209.754983] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [209.755401] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [209.755785] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [209.756166] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [209.756589] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [209.756969] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [209.757374] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [209.757753] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [209.758215] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [209.758636] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [209.759017] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [209.759425] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [209.759918] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [209.760381] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [209.760785] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [209.761165] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [209.761597] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [209.762008] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [209.762429] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [209.762811] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [209.763192] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [209.763627] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [209.764012] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [209.764446] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [209.764831] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [209.765208] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in port_clock (expected 0, found 222750)
<7> [209.765616] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [209.765995] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006)
<7> [209.766406] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in avi infoframe
<7> [209.766789] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [209.767168] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [209.767655] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [209.767660] i915 0000:00:02.0: colorspace: RGB
<7> [209.767664] i915 0000:00:02.0: scan mode: Underscan
<7> [209.767668] i915 0000:00:02.0: colorimetry: No Data
<7> [209.767671] i915 0000:00:02.0: picture aspect: No Data
<7> [209.767675] i915 0000:00:02.0: active aspect: Same as Picture
<7> [209.767679] i915 0000:00:02.0: itc: No Data
<7> [209.767682] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [209.767686] i915 0000:00:02.0: quantization range: Full
<7> [209.767689] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [209.767693] i915 0000:00:02.0: video code: 0
<7> [209.767696] i915 0000:00:02.0: ycc quantization range: Full
<7> [209.767699] i915 0000:00:02.0: hdmi content type: Graphics
<7> [209.767703] i915 0000:00:02.0: pixel repeat: 0
<7> [209.767706] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [209.767710] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in spd infoframe
<7> [209.768090] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [209.768526] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [209.768906] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [209.768910] i915 0000:00:02.0: vendor: Intel
<7> [209.768914] i915 0000:00:02.0: product: Integrated gfx
<7> [209.768918] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [209.768922] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hdmi infoframe
<7> [209.769428] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [209.769830] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [209.770210] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [209.770214] i915 0000:00:02.0: empty frame
<7> [209.770220] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:164:pipe C] fastset requirement not met, forcing full modeset
<7> [209.770763] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:164:pipe C] dbuf slices 0x0 -> 0x1, ddb (0 - 0) -> (0 - 1020), active pipes 0x0 -> 0x4
<7> [209.771218] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:122:plane 1C] ddb ( 0 - 0) -> ( 0 - 988), size 0 -> 988
<7> [209.771640] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:158:cursor C] ddb ( 0 - 0) -> ( 988 - 1020), size 0 -> 32
<7> [209.772044] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:122:plane 1C] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [209.772443] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:122:plane 1C] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 1, 1, 2, 2, 2, 2, 2, 0, 0, 0
<7> [209.772820] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:122:plane 1C] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 16, 16, 33, 33, 33, 33, 33, 30, 0, 0
<7> [209.773195] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:122:plane 1C] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 17, 17, 17, 34, 34, 34, 34, 34, 31, 0, 0
<7> [209.773624] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:122:plane 1C] min_ddb_uv 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [209.774105] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:164:pipe C] min cdclk: 0 kHz -> 192000 kHz
<7> [209.774593] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 18563 kHz -> 9282 kHz
<7> [209.774977] i915 0000:00:02.0: [drm:bxt_get_dpll [i915]] [CRTC:164:pipe C] using pre-allocated PORT PLL C
<7> [209.775388] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:164:pipe C] reserving PORT PLL C
<7> [209.775779] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:164:pipe C] enable: yes [modeset]
<7> [209.776165] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [209.776575] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: C, pipe bpp: 36, dithering: 0
<7> [209.776954] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [209.777365] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [209.777828] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [209.778209] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [209.778620] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [209.779126] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x6
<7> [209.779597] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [209.779602] i915 0000:00:02.0: colorspace: RGB
<7> [209.779606] i915 0000:00:02.0: scan mode: Underscan
<7> [209.779609] i915 0000:00:02.0: colorimetry: No Data
<7> [209.779632] i915 0000:00:02.0: picture aspect: No Data
<7> [209.779636] i915 0000:00:02.0: active aspect: Same as Picture
<7> [209.779640] i915 0000:00:02.0: itc: No Data
<7> [209.779643] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [209.779647] i915 0000:00:02.0: quantization range: Full
<7> [209.779650] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [209.779653] i915 0000:00:02.0: video code: 0
<7> [209.779657] i915 0000:00:02.0: ycc quantization range: Full
<7> [209.779660] i915 0000:00:02.0: hdmi content type: Graphics
<7> [209.779664] i915 0000:00:02.0: pixel repeat: 0
<7> [209.779667] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [209.779671] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [209.779674] i915 0000:00:02.0: vendor: Intel
<7> [209.779678] i915 0000:00:02.0: product: Integrated gfx
<7> [209.779681] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [209.779685] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [209.779688] i915 0000:00:02.0: empty frame
<7> [209.779692] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 10 00 07 00 66 10 00 01 00 00 00 00 00 00 00 00
<7> [209.780072] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 05 e3 cd 0c 32 38 45 38 35 30 09 07 07 00 00 00
<7> [209.780506] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [209.780917] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [209.781326] 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> [209.781706] 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> [209.782085] 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> [209.782568] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [209.782958] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [209.783370] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] 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> [209.783755] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x40 0x9
<7> [209.784136] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] 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> [209.784558] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 222750, pixel rate 148500, min cdclk 192000, min voltage level 0
<7> [209.784938] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [209.785337] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 1, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [209.785716] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [209.786096] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [209.786502] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [209.786879] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [209.787288] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: ebb0: 0x6200, ebb4: 0x2000,pll0: 0x21, pll1: 0x100, pll2: 0x1a6666, pll3: 0x10000, pll6: 0x30904, pll8: 0x8, pll9: 0xa, pll10: 0x8003c00, pcsdw12: 0x4d
<7> [209.787752] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [209.788134] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [209.788545] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [209.788924] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [209.789418] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [209.789936] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [209.790373] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [209.790755] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:122:plane 1C] fb: [FB:184] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [209.791136] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [209.791571] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [209.791981] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:131:plane 2C] fb: [NOFB], visible: no
<7> [209.792388] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:140:plane 3C] fb: [NOFB], visible: no
<7> [209.792767] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:149:plane 4C] fb: [NOFB], visible: no
<7> [209.793145] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:158:cursor C] fb: [NOFB], visible: no
<7> [209.794239] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling dpio-common-c
<7> [209.795530] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:165:DDI B/PHY B]
<7> [209.795935] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:175:DDI C/PHY C]
<7> [209.796504] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PORT PLL C (active 0x4, on? 0) for [CRTC:164:pipe C]
<7> [209.796895] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PORT PLL C
<7> [209.798504] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_C
<7> [209.799018] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe C
<7> [209.817244] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:176:HDMI-A-2][ENCODER:175:DDI C/PHY C] Enable audio codec on [CRTC:164:pipe C], 32 bytes ELD
<7> [209.833902] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [209.834416] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<7> [209.834988] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:176:HDMI-A-2]
<7> [209.837152] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:164:pipe C]
<7> [209.839160] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:78:pipe A]
<7> [209.839226] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:166:HDMI-A-1]
<7> [209.839473] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [209.839931] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:78:pipe A] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [209.840341] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:165:DDI B/PHY B] [CRTC:78:pipe A]
<7> [209.840717] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [209.841099] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [209.841782] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [209.842193] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [209.842615] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in lane_lat_optim_mask (expected 0x00000000, found 0x0000000d)
<7> [209.843027] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [209.843487] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [209.843865] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [209.844241] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [209.844649] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [209.845030] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [209.845459] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [209.845836] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [209.846211] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [209.849022] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [209.849623] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [209.850007] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [209.850465] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [209.850848] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [209.851340] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [209.851746] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [209.852127] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [209.852561] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [209.852962] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [209.853416] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [209.853823] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [209.854203] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [209.855914] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [209.856434] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [209.856816] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [209.857193] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [209.857600] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [209.857979] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [209.859467] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [209.859947] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [209.860395] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [209.860847] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [209.861246] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [209.861674] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [209.862075] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in port_clock (expected 0, found 222750)
<7> [209.862541] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [209.862950] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006)
<7> [209.863370] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in avi infoframe
<7> [209.863749] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [209.864127] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [209.866807] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [209.866814] i915 0000:00:02.0: colorspace: RGB
<7> [209.866915] i915 0000:00:02.0: scan mode: Underscan
<7> [209.866921] i915 0000:00:02.0: colorimetry: No Data
<7> [209.866924] i915 0000:00:02.0: picture aspect: No Data
<7> [209.866928] i915 0000:00:02.0: active aspect: Same as Picture
<7> [209.866931] i915 0000:00:02.0: itc: No Data
<7> [209.866934] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [209.866938] i915 0000:00:02.0: quantization range: Full
<7> [209.866941] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [209.866944] i915 0000:00:02.0: video code: 0
<7> [209.866947] i915 0000:00:02.0: ycc quantization range: Full
<7> [209.866951] i915 0000:00:02.0: hdmi content type: Graphics
<7> [209.866954] i915 0000:00:02.0: pixel repeat: 0
<7> [209.866957] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [209.866962] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in spd infoframe
<7> [209.867510] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [209.867893] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [209.868299] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [209.868304] i915 0000:00:02.0: vendor: Intel
<7> [209.868307] i915 0000:00:02.0: product: Integrated gfx
<7> [209.868311] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [209.868315] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hdmi infoframe
<7> [209.868693] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [209.869185] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [209.869629] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [209.869633] i915 0000:00:02.0: empty frame
<7> [209.869639] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:78:pipe A] fastset requirement not met, forcing full modeset
<7> [209.870101] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:78:pipe A] dbuf slices 0x0 -> 0x1, ddb (0 - 0) -> (0 - 510), active pipes 0x4 -> 0x5
<7> [209.870594] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:164:pipe C] dbuf slices 0x1 -> 0x1, ddb (0 - 1020) -> (510 - 1020), active pipes 0x4 -> 0x5
<7> [209.871056] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:36:plane 1A] ddb ( 0 - 0) -> ( 0 - 497), size 0 -> 497
<7> [209.871518] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:72:cursor A] ddb ( 0 - 0) -> ( 497 - 510), size 0 -> 13
<7> [209.871921] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:36: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> [209.872330] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:36:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 1, 1, 2, 2, 2, 2, 2, 0, 0, 0
<7> [209.872704] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:36:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 16, 16, 33, 33, 33, 33, 33, 30, 0, 0
<7> [209.873076] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:36:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 17, 17, 17, 34, 34, 34, 34, 34, 31, 0, 0
<7> [209.875339] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:36:plane 1A] min_ddb_uv 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [209.875829] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:122:plane 1C] ddb ( 0 - 988) -> ( 510 - 1007), size 988 -> 497
<7> [209.876201] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:158:cursor C] ddb ( 988 - 1020) -> (1007 - 1020), size 32 -> 13
<7> [209.876693] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:78:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [209.877116] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 9282 kHz -> 18563 kHz
<7> [209.878775] i915 0000:00:02.0: [drm:bxt_get_dpll [i915]] [CRTC:78:pipe A] using pre-allocated PORT PLL B
<7> [209.879247] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:78:pipe A] reserving PORT PLL B
<7> [209.879698] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:78:pipe A] enable: yes [modeset]
<7> [209.880158] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [209.880583] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 36, dithering: 0
<7> [209.880960] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [209.881391] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [209.881790] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [209.882168] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [209.882644] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [209.883054] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x6
<7> [209.883468] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [209.883473] i915 0000:00:02.0: colorspace: RGB
<7> [209.883477] i915 0000:00:02.0: scan mode: Underscan
<7> [209.883480] i915 0000:00:02.0: colorimetry: No Data
<7> [209.883484] i915 0000:00:02.0: picture aspect: No Data
<7> [209.883487] i915 0000:00:02.0: active aspect: Same as Picture
<7> [209.883490] i915 0000:00:02.0: itc: No Data
<7> [209.883494] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [209.883498] i915 0000:00:02.0: quantization range: Full
<7> [209.883501] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [209.883505] i915 0000:00:02.0: video code: 0
<7> [209.883508] i915 0000:00:02.0: ycc quantization range: Full
<7> [209.883511] i915 0000:00:02.0: hdmi content type: Graphics
<7> [209.883515] i915 0000:00:02.0: pixel repeat: 0
<7> [209.883518] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [209.883522] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [209.883526] i915 0000:00:02.0: vendor: Intel
<7> [209.883530] i915 0000:00:02.0: product: Integrated gfx
<7> [209.883534] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [209.883537] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [209.883541] i915 0000:00:02.0: empty frame
<7> [209.883544] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 10 00 07 00 66 10 00 01 00 00 00 00 00 00 00 00
<7> [209.883924] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 05 e3 cd 0c 32 38 45 38 35 30 09 07 07 00 00 00
<7> [209.886316] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [209.886803] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [209.887181] 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> [209.887609] 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> [209.887989] 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> [209.889191] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [209.889724] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [209.890108] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] 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> [209.890573] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x40 0x9
<7> [209.890956] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] 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> [209.891450] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 222750, pixel rate 148500, min cdclk 192000, min voltage level 0
<7> [209.891849] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [209.892226] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [209.892660] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [209.893058] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [209.893477] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [209.893879] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [209.896476] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: ebb0: 0x6200, ebb4: 0x2000,pll0: 0x21, pll1: 0x100, pll2: 0x1a6666, pll3: 0x10000, pll6: 0x30904, pll8: 0x8, pll9: 0xa, pll10: 0x8003c00, pcsdw12: 0x4d
<7> [209.897040] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [209.897498] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [209.897881] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [209.898345] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [209.898743] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [209.899121] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [209.899562] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [209.899966] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:36:plane 1A] fb: [FB:184] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [209.900432] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [209.900839] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [209.901220] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:45:plane 2A] fb: [NOFB], visible: no
<7> [209.902945] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:54:plane 3A] fb: [NOFB], visible: no
<7> [209.903455] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:63:plane 4A] fb: [NOFB], visible: no
<7> [209.903835] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:72:cursor A] fb: [NOFB], visible: no
<7> [209.905766] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling dpio-common-b
<7> [209.906553] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:165:DDI B/PHY B]
<7> [209.907061] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:175:DDI C/PHY C]
<7> [209.917328] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PORT PLL B (active 0x1, on? 0) for [CRTC:78:pipe A]
<7> [209.917825] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PORT PLL B
<7> [209.919529] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [209.920118] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [209.921314] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 16588800 bytes of contiguous stolen space for FBC, limit: 1
<7> [209.921710] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:36:plane 1A]
<7> [209.937704] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:166:HDMI-A-1][ENCODER:165:DDI B/PHY B] Enable audio codec on [CRTC:78:pipe A], 32 bytes ELD
<7> [209.954352] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [209.954831] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<7> [209.955413] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:166:HDMI-A-1]
<7> [209.955978] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:78:pipe A]
<6> [212.160442] [IGT] kms_flip: finished subtest AC-HDMI-A1-HDMI-A2, FAIL
Created at 2026-04-07 10:37:56