Results for igt@kms_flip@wf_vblank-ts-check-interruptible@b-vga1

Machine description: shard-snb6

Result: Fail

integration-manifest git-log-oneline i915_display_info0 igt_runner0 run0 runtimes0 results0.json i915_display_info_post_exec0 boot0 dmesg0

DetailValue
Duration 10.95 seconds
Hostname 
shard-snb6
Igt-Version 
IGT-Version: 1.29-NO-GIT (x86_64) (Linux: 6.12.0-rc1-CI_DRM_15469-gfcdbbdcd11d4+ x86_64)
Out 
Starting dynamic subtest: B-VGA1
  1024x768: 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa 
Expected frametime: 16666us; measured 16816.4us +- 7.182us accuracy 0.13%
vblank interval differs from modeline! expected 16665.6us, measured 16816us +- 7.182us, difference 150.8us (21.0 sigma)
Stack trace:
  #0 ../lib/igt_core.c:2051 __igt_fail_assert()
  #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1845 run_test()
  #3 ../tests/kms_flip.c:2114 __igt_unique____real_main2001()
  #4 ../tests/kms_flip.c:2001 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest B-VGA1: FAIL (10.950s)
Err 
Starting dynamic subtest: B-VGA1
(kms_flip:1497) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:1497) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
(kms_flip:1497) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest B-VGA1 failed.
**** DEBUG ****
(kms_flip:1497) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1024, height=768, format=XR24(0x34325258), modifier=0x0, size=0)
(kms_flip:1497) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=3, pitch=4096)
(kms_flip:1497) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:1497) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1024, height=768, format=XR24(0x34325258), modifier=0x0, size=0)
(kms_flip:1497) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=4, pitch=4096)
(kms_flip:1497) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:1497) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:1497) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:1497) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS
(kms_flip:1497) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:1497) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:1497) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS
(kms_flip:1497) igt_kms-INFO:   1024x768: 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa 
(kms_flip:1497) DEBUG: No stale events found
(kms_flip:1497) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:1497) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:1497) INFO: Expected frametime: 16666us; measured 16816.4us +- 7.182us accuracy 0.13%
(kms_flip:1497) INFO: vblank interval differs from modeline! expected 16665.6us, measured 16816us +- 7.182us, difference 150.8us (21.0 sigma)
(kms_flip:1497) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 54.354664
current_received_ts = 54.354084
current_seq = 32
count = 0
seq_step = 10
(kms_flip:1497) DEBUG: name = vblank
last_ts = 54.354664
last_received_ts = 54.354084
last_seq = 32
current_ts = 54.522827
current_received_ts = 54.522224
current_seq = 42
count = 1
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 54.354664/32, current 54.522827/42: elapsed=168166.0us expected=168163.8us +- 840.8us, error 0.0%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 54.522827
last_received_ts = 54.522224
last_seq = 42
current_ts = 54.690907
current_received_ts = 54.690247
current_seq = 52
count = 2
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 54.522827/42, current 54.690907/52: elapsed=168080.0us expected=168163.8us +- 840.8us, error 0.0%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 54.690907
last_received_ts = 54.690247
last_seq = 52
current_ts = 54.858936
current_received_ts = 54.858334
current_seq = 62
count = 3
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 54.690907/52, current 54.858936/62: elapsed=168027.0us expected=168163.8us +- 840.8us, error 0.1%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 54.858936
last_received_ts = 54.858334
last_seq = 62
current_ts = 55.026974
current_received_ts = 55.026375
current_seq = 72
count = 4
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 54.858936/62, current 55.026974/72: elapsed=168038.0us expected=168163.8us +- 840.8us, error 0.1%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 55.026974
last_received_ts = 55.026375
last_seq = 72
current_ts = 55.195019
current_received_ts = 55.194393
current_seq = 82
count = 5
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 55.026974/72, current 55.195019/82: elapsed=168046.0us expected=168163.8us +- 840.8us, error 0.1%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 55.195019
last_received_ts = 55.194393
last_seq = 82
current_ts = 55.363045
current_received_ts = 55.362419
current_seq = 92
count = 6
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 55.195019/82, current 55.363045/92: elapsed=168026.0us expected=168163.8us +- 840.8us, error 0.1%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 55.363045
last_received_ts = 55.362419
last_seq = 92
current_ts = 55.531086
current_received_ts = 55.530483
current_seq = 102
count = 7
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 55.363045/92, current 55.531086/102: elapsed=168041.0us expected=168163.8us +- 840.8us, error 0.1%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 55.531086
last_received_ts = 55.530483
last_seq = 102
current_ts = 55.699051
current_received_ts = 55.698326
current_seq = 112
count = 8
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 55.531086/102, current 55.699051/112: elapsed=167963.0us expected=168163.8us +- 840.8us, error 0.1%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 55.699051
last_received_ts = 55.698326
last_seq = 112
current_ts = 55.866974
current_received_ts = 55.867035
current_seq = 122
count = 9
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 55.699051/112, current 55.866974/122: elapsed=167924.0us expected=168163.8us +- 840.8us, error 0.1%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 55.866974
last_received_ts = 55.867035
last_seq = 122
current_ts = 56.034904
current_received_ts = 56.034225
current_seq = 132
count = 10
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 55.866974/122, current 56.034904/132: elapsed=167933.0us expected=168163.8us +- 840.8us, error 0.1%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 56.034904
last_received_ts = 56.034225
last_seq = 132
current_ts = 56.202824
current_received_ts = 56.202240
current_seq = 142
count = 11
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 56.034904/132, current 56.202824/142: elapsed=167918.0us expected=168163.8us +- 840.8us, error 0.1%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 56.202824
last_received_ts = 56.202240
last_seq = 142
current_ts = 56.370750
current_received_ts = 56.370117
current_seq = 152
count = 12
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 56.202824/142, current 56.370750/152: elapsed=167926.0us expected=168163.8us +- 840.8us, error 0.1%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 56.370750
last_received_ts = 56.370117
last_seq = 152
current_ts = 56.538681
current_received_ts = 56.538052
current_seq = 162
count = 13
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 56.370750/152, current 56.538681/162: elapsed=167932.0us expected=168163.8us +- 840.8us, error 0.1%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 56.538681
last_received_ts = 56.538052
last_seq = 162
current_ts = 56.706532
current_received_ts = 56.705940
current_seq = 172
count = 14
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 56.538681/162, current 56.706532/172: elapsed=167850.0us expected=168163.8us +- 840.8us, error 0.2%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 56.706532
last_received_ts = 56.705940
last_seq = 172
current_ts = 56.874363
current_received_ts = 56.873734
current_seq = 182
count = 15
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 56.706532/172, current 56.874363/182: elapsed=167830.0us expected=168163.8us +- 840.8us, error 0.2%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 56.874363
last_received_ts = 56.873734
last_seq = 182
current_ts = 57.042198
current_received_ts = 57.041573
current_seq = 192
count = 16
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 56.874363/182, current 57.042198/192: elapsed=167838.0us expected=168163.8us +- 840.8us, error 0.2%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 57.042198
last_received_ts = 57.041573
last_seq = 192
current_ts = 57.210037
current_received_ts = 57.209408
current_seq = 202
count = 17
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 57.042198/192, current 57.210037/202: elapsed=167836.0us expected=168163.8us +- 840.8us, error 0.2%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 57.210037
last_received_ts = 57.209408
last_seq = 202
current_ts = 57.377872
current_received_ts = 57.377220
current_seq = 212
count = 18
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 57.210037/202, current 57.377872/212: elapsed=167836.0us expected=168163.8us +- 840.8us, error 0.2%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 57.377872
last_received_ts = 57.377220
last_seq = 212
current_ts = 57.545700
current_received_ts = 57.545055
current_seq = 222
count = 19
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 57.377872/212, current 57.545700/222: elapsed=167830.0us expected=168163.8us +- 840.8us, error 0.2%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 57.545700
last_received_ts = 57.545055
last_seq = 222
current_ts = 57.713463
current_received_ts = 57.712833
current_seq = 232
count = 20
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 57.545700/222, current 57.713463/232: elapsed=167761.0us expected=168163.8us +- 840.8us, error 0.2%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 57.713463
last_received_ts = 57.712833
last_seq = 232
current_ts = 57.881226
current_received_ts = 57.880573
current_seq = 242
count = 21
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 57.713463/232, current 57.881226/242: elapsed=167762.0us expected=168163.8us +- 840.8us, error 0.2%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 57.881226
last_received_ts = 57.880573
last_seq = 242
current_ts = 58.048965
current_received_ts = 58.048336
current_seq = 252
count = 22
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 57.881226/242, current 58.048965/252: elapsed=167740.0us expected=168163.8us +- 840.8us, error 0.3%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 58.048965
last_received_ts = 58.048336
last_seq = 252
current_ts = 58.216717
current_received_ts = 58.216290
current_seq = 262
count = 23
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 58.048965/252, current 58.216717/262: elapsed=167751.0us expected=168163.8us +- 840.8us, error 0.2%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 58.216717
last_received_ts = 58.216290
last_seq = 262
current_ts = 58.384476
current_received_ts = 58.383816
current_seq = 272
count = 24
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 58.216717/262, current 58.384476/272: elapsed=167758.0us expected=168163.8us +- 840.8us, error 0.2%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 58.384476
last_received_ts = 58.383816
last_seq = 272
current_ts = 58.552216
current_received_ts = 58.551540
current_seq = 282
count = 25
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 58.384476/272, current 58.552216/282: elapsed=167742.0us expected=168163.8us +- 840.8us, error 0.3%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 58.552216
last_received_ts = 58.551540
last_seq = 282
current_ts = 58.719906
current_received_ts = 58.719318
current_seq = 292
count = 26
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 58.552216/282, current 58.719906/292: elapsed=167691.0us expected=168163.8us +- 840.8us, error 0.3%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 58.719906
last_received_ts = 58.719318
last_seq = 292
current_ts = 58.887581
current_received_ts = 58.886978
current_seq = 302
count = 27
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 58.719906/292, current 58.887581/302: elapsed=167674.0us expected=168163.8us +- 840.8us, error 0.3%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 58.887581
last_received_ts = 58.886978
last_seq = 302
current_ts = 59.055256
current_received_ts = 59.054676
current_seq = 312
count = 28
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 58.887581/302, current 59.055256/312: elapsed=167676.0us expected=168163.8us +- 840.8us, error 0.3%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 59.055256
last_received_ts = 59.054676
last_seq = 312
current_ts = 59.222946
current_received_ts = 59.222321
current_seq = 322
count = 29
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 59.055256/312, current 59.222946/322: elapsed=167688.0us expected=168163.8us +- 840.8us, error 0.3%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 59.222946
last_received_ts = 59.222321
last_seq = 322
current_ts = 59.390614
current_received_ts = 59.389992
current_seq = 332
count = 30
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 59.222946/322, current 59.390614/332: elapsed=167670.0us expected=168163.8us +- 840.8us, error 0.3%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 59.390614
last_received_ts = 59.389992
last_seq = 332
current_ts = 59.558292
current_received_ts = 59.557667
current_seq = 342
count = 31
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 59.390614/332, current 59.558292/342: elapsed=167676.0us expected=168163.8us +- 840.8us, error 0.3%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 59.558292
last_received_ts = 59.557667
last_seq = 342
current_ts = 59.725903
current_received_ts = 59.725357
current_seq = 352
count = 32
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 59.558292/342, current 59.725903/352: elapsed=167612.0us expected=168163.8us +- 840.8us, error 0.3%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 59.725903
last_received_ts = 59.725357
last_seq = 352
current_ts = 59.893520
current_received_ts = 59.892921
current_seq = 362
count = 33
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 59.725903/352, current 59.893520/362: elapsed=167618.0us expected=168163.8us +- 840.8us, error 0.3%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 59.893520
last_received_ts = 59.892921
last_seq = 362
current_ts = 60.061138
current_received_ts = 60.060535
current_seq = 372
count = 34
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 59.893520/362, current 60.061138/372: elapsed=167619.0us expected=168163.8us +- 840.8us, error 0.3%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 60.061138
last_received_ts = 60.060535
last_seq = 372
current_ts = 60.228756
current_received_ts = 60.228168
current_seq = 382
count = 35
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 60.061138/372, current 60.228756/382: elapsed=167615.0us expected=168163.8us +- 840.8us, error 0.3%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 60.228756
last_received_ts = 60.228168
last_seq = 382
current_ts = 60.396374
current_received_ts = 60.395748
current_seq = 392
count = 36
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 60.228756/382, current 60.396374/392: elapsed=167620.0us expected=168163.8us +- 840.8us, error 0.3%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 60.396374
last_received_ts = 60.395748
last_seq = 392
current_ts = 60.563984
current_received_ts = 60.563377
current_seq = 402
count = 37
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 60.396374/392, current 60.563984/402: elapsed=167608.0us expected=168163.8us +- 840.8us, error 0.3%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 60.563984
last_received_ts = 60.563377
last_seq = 402
current_ts = 60.731544
current_received_ts = 60.730942
current_seq = 412
count = 38
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 60.563984/402, current 60.731544/412: elapsed=167563.0us expected=168163.8us +- 840.8us, error 0.4%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 60.731544
last_received_ts = 60.730942
last_seq = 412
current_ts = 60.899097
current_received_ts = 60.898491
current_seq = 422
count = 39
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 60.731544/412, current 60.899097/422: elapsed=167552.0us expected=168163.8us +- 840.8us, error 0.4%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 60.899097
last_received_ts = 60.898491
last_seq = 422
current_ts = 61.066662
current_received_ts = 61.065994
current_seq = 432
count = 40
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 60.899097/422, current 61.066662/432: elapsed=167564.0us expected=168163.8us +- 840.8us, error 0.4%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 61.066662
last_received_ts = 61.065994
last_seq = 432
current_ts = 61.234222
current_received_ts = 61.233616
current_seq = 442
count = 41
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 61.066662/432, current 61.234222/442: elapsed=167560.0us expected=168163.8us +- 840.8us, error 0.4%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 61.234222
last_received_ts = 61.233616
last_seq = 442
current_ts = 61.401772
current_received_ts = 61.401169
current_seq = 452
count = 42
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 61.234222/442, current 61.401772/452: elapsed=167549.0us expected=168163.8us +- 840.8us, error 0.4%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 61.401772
last_received_ts = 61.401169
last_seq = 452
current_ts = 61.569340
current_received_ts = 61.568737
current_seq = 462
count = 43
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 61.401772/452, current 61.569340/462: elapsed=167568.0us expected=168163.8us +- 840.8us, error 0.4%
(kms_flip:1497) DEBUG: name = vblank
last_ts = 61.569340
last_received_ts = 61.568737
last_seq = 462
current_ts = 61.736847
current_received_ts = 61.736244
current_seq = 472
count = 44
seq_step = 10
(kms_flip:1497) DEBUG: vblank ts/seq: last 61.569340/462, current 61.736847/472: elapsed=167509.0us expected=168163.8us +- 840.8us, error 0.4%
(kms_flip:1497) DEBUG: expected 448, counted 450, encoder type 1
(kms_flip:1497) DEBUG: dropped frames, expected 448, counted 450, encoder type 1
(kms_flip:1497) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:1497) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
(kms_flip:1497) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:1497) igt_core-INFO: Stack trace:
(kms_flip:1497) igt_core-INFO:   #0 ../lib/igt_core.c:2051 __igt_fail_assert()
(kms_flip:1497) igt_core-INFO:   #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0()
(kms_flip:1497) igt_core-INFO:   #2 ../tests/kms_flip.c:1845 run_test()
(kms_flip:1497) igt_core-INFO:   #3 ../tests/kms_flip.c:2114 __igt_unique____real_main2001()
(kms_flip:1497) igt_core-INFO:   #4 ../tests/kms_flip.c:2001 main()
(kms_flip:1497) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:1497) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:1497) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest B-VGA1: FAIL (10.950s)
Dmesg
<6> [53.590399] [IGT] kms_flip: starting dynamic subtest B-VGA1
<7> [53.590913] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [53.590977] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [53.604565] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:47:pipe A]
<7> [53.604663] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [53.604900] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [53.605112] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [53.605314] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in has_pch_encoder (expected yes, found no)
<7> [53.605485] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in fdi_lanes (expected 1, found 0)
<7> [53.605667] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in fdi_m_n (expected tu 64 data 6039797/8388608 link 125829/524288, found tu 0, data 0/0 link 0/0)
<7> [53.605854] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in output_types (expected 0x00000002, found 0x00000000)
<7> [53.606062] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [53.606238] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1024, found 0)
<7> [53.606415] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 1344, found 0)
<7> [53.606591] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1024, found 0)
<7> [53.606794] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 1344, found 0)
<7> [53.607005] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1048, found 0)
<7> [53.607191] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 1184, found 0)
<7> [53.607377] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 768, found 0)
<7> [53.607563] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 768, found 0)
<7> [53.607779] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 771, found 0)
<7> [53.607969] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 777, found 0)
<7> [53.608150] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 806, found 0)
<7> [53.608331] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 806, found 0)
<7> [53.608511] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1024, found 0)
<7> [53.608692] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 1344, found 0)
<7> [53.608886] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1024, found 0)
<7> [53.609068] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 1344, found 0)
<7> [53.609248] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1048, found 0)
<7> [53.609428] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 1184, found 0)
<7> [53.609609] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 768, found 0)
<7> [53.609933] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 768, found 0)
<7> [53.610116] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 771, found 0)
<7> [53.610297] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 777, found 0)
<7> [53.610477] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 806, found 0)
<7> [53.610658] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 806, found 0)
<7> [53.610846] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [53.611027] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [53.611208] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [53.611394] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [53.611570] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 64800, found 0)
<7> [53.611759] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 64800, found 0)
<7> [53.611952] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in port_clock (expected 64800, found 0)
<7> [53.612128] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:47:pipe A] fastset requirement not met, forcing full modeset
<7> [53.612304] i915 0000:00:02.0: [drm:intel_unreference_shared_dpll_crtc [i915]] [CRTC:47:pipe A] releasing PCH DPLL A
<7> [53.612494] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 400000 kHz, actual 400000 kHz
<7> [53.612706] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [53.612964] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:47:pipe A] enable: no [modeset]
<7> [53.613147] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:primary A] fb: [NOFB], visible: no
<7> [53.613450] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [53.617917] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found
<7> [53.618103] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found
<7> [53.618321] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found
<7> [53.635509] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disable PCH DPLL A (active 0x1, on? 1) for [CRTC:47:pipe A]
<7> [53.635812] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disabling PCH DPLL A
<7> [53.636332] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:65:CRT]
<7> [53.636678] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:66:HDMI D]
<7> [53.636912] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:DP D]
<7> [53.637134] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:64:VGA-1]
<7> [53.637340] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:47:pipe A]
<7> [53.637620] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:63:pipe B]
<7> [53.637774] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:63:pipe B]
<7> [53.637795] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:64:VGA-1]
<7> [53.637857] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:64:VGA-1] Limiting display bpp to 24 (EDID bpp 0, max requested bpp 0, max platform bpp 36)
<7> [53.638052] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:63:pipe B] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [53.638250] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe B, lanes 1
<7> [53.638441] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.enable (expected no, found yes)
<7> [53.638648] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.active (expected no, found yes)
<7> [53.638831] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in has_pch_encoder (expected no, found yes)
<7> [53.639012] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in fdi_lanes (expected 0, found 1)
<7> [53.639193] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in fdi_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6039797/8388608 link 125829/524288)
<7> [53.639376] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in output_types (expected 0x00000000, found 0x00000002)
<7> [53.639612] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [53.639797] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1024)
<7> [53.639979] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 1344)
<7> [53.640160] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1024)
<7> [53.640340] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 1344)
<7> [53.640530] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1048)
<7> [53.640711] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 1184)
<7> [53.640891] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 768)
<7> [53.641072] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 768)
<7> [53.641252] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 771)
<7> [53.641432] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 777)
<7> [53.641637] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 806)
<7> [53.641818] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 806)
<7> [53.641999] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1024)
<7> [53.642179] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 1344)
<7> [53.642360] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1024)
<7> [53.642549] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 1344)
<7> [53.642730] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1048)
<7> [53.642911] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 1184)
<7> [53.643122] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 768)
<7> [53.643305] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 768)
<7> [53.643486] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 771)
<7> [53.643701] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 777)
<7> [53.643884] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 806)
<7> [53.644065] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 806)
<7> [53.644246] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [53.644427] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [53.644615] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [53.644797] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in dpll_hw_state
<7> [53.644978] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [53.645158] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0x0, dpll_md: 0x0, fp0: 0x0, fp1: 0x0
<7> [53.645339] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [53.645526] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [53.645715] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [53.645899] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 64800)
<7> [53.646081] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 64800)
<7> [53.646261] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in port_clock (expected 0, found 64800)
<7> [53.646442] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:63:pipe B] fastset requirement not met, forcing full modeset
<7> [53.646658] i915 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [i915]] [PLANE:48:primary B] min cdclk (64800 kHz) > [CRTC:63:pipe B] min cdclk (0 kHz)
<7> [53.646844] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 400000 kHz, actual 400000 kHz
<7> [53.647026] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [53.647209] i915 0000:00:02.0: [drm:intel_find_shared_dpll [i915]] [CRTC:63:pipe B] allocated PCH DPLL A
<7> [53.647385] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc [i915]] [CRTC:63:pipe B] reserving PCH DPLL A
<7> [53.647571] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:63:pipe B] enable: yes [modeset]
<7> [53.647753] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB
<7> [53.647935] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: B, pipe bpp: 24, dithering: 0
<7> [53.648131] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [53.648343] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [53.648563] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [53.648750] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [53.648934] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] fdi: lanes: 1; data_m: 6039797, data_n: 8388608, link_m: 125829, link_n: 524288, tu: 64
<7> [53.649117] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0
<7> [53.649298] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [53.649499] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, vmin: 0, vmax: 0, pipeline full: 0, guardband: 0 flipline: 0, vmin vblank: -1, vmax vblank: -2
<7> [53.649682] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [53.649865] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [53.650048] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=64800, hd=1024 hb=1024-1344 hs=1048-1184 ht=1344, vd=768 vb=768-806 vs=771-777 vt=806, flags=0xa
<7> [53.650231] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1024x768": 60 64800 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [53.650414] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=64800, hd=1024 hb=1024-1344 hs=1048-1184 ht=1344, vd=768 vb=768-806 vs=771-777 vt=806, flags=0xa
<7> [53.650605] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 64800, pipe src: 1024x768+0+0, pixel rate 64800
<7> [53.650788] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0
<7> [53.650969] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [53.651150] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [53.651331] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [53.651531] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [53.651712] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [53.651893] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [53.652075] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [53.652256] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [53.652476] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [53.652671] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:48:primary B] fb: [FB:84] 1024x768 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [53.652858] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [53.653040] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0
<7> [53.654448] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:65:CRT]
<7> [53.654645] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:66:HDMI D]
<7> [53.654850] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:DP D]
<7> [53.655610] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe B
<7> [53.656632] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100
<7> [53.656811] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done.
<7> [53.657647] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x600
<7> [53.657824] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done.
<7> [53.657998] i915 0000:00:02.0: [drm:ilk_pch_enable [i915]] FDI train done.
<7> [53.658174] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enable PCH DPLL A (active 0x2, on? 0) for [CRTC:63:pipe B]
<7> [53.658352] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling PCH DPLL A
<7> [53.710366] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:64:VGA-1]
<7> [53.710797] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:63:pipe B]
<6> [64.540677] [IGT] kms_flip: finished subtest B-VGA1, FAIL
Created at 2024-10-08 11:02:44