Results for igt@kms_flip@plain-flip-ts-check@b-vga1

Machine description: shard-snb1

Result: Fail

git-log-oneline i915_display_info16 igt_runner16 run16 runtimes16 results16.json i915_display_info_post_exec16 boot16 dmesg16

DetailValue
Duration 5.07 seconds
Hostname 
shard-snb1
Igt-Version 
IGT-Version: 1.29-NO-GIT (x86_64) (Linux: 6.12.0-rc3-CI_DRM_15559-gc1837d4e9af4+ 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 16307.2us +- 4.640us accuracy 0.09%
vblank interval differs from modeline! expected 16665.6us, measured 16307us +- 4.640us, difference 358.3us (77.2 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:2078 __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 (5.074s)
Err 
Starting dynamic subtest: B-VGA1
(kms_flip:1493) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:1493) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
Dynamic subtest B-VGA1 failed.
**** DEBUG ****
(kms_flip:1493) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1024, height=768, format=XR24(0x34325258), modifier=0x0, size=0)
(kms_flip:1493) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=3, pitch=4096)
(kms_flip:1493) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:1493) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1024, height=768, format=XR24(0x34325258), modifier=0x0, size=0)
(kms_flip:1493) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=4, pitch=4096)
(kms_flip:1493) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:1493) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:1493) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:1493) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS
(kms_flip:1493) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:1493) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:1493) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS
(kms_flip:1493) igt_kms-INFO:   1024x768: 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa 
(kms_flip:1493) DEBUG: No stale events found
(kms_flip:1493) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:1493) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:1493) INFO: Expected frametime: 16666us; measured 16307.2us +- 4.640us accuracy 0.09%
(kms_flip:1493) INFO: vblank interval differs from modeline! expected 16665.6us, measured 16307us +- 4.640us, difference 358.3us (77.2 sigma)
(kms_flip:1493) DEBUG: name = flip
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 46.055050
current_received_ts = 46.054447
current_seq = 23
count = 0
seq_step = 1
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.055050
last_received_ts = 46.054447
last_seq = 23
current_ts = 46.071350
current_received_ts = 46.070721
current_seq = 24
count = 1
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.055050/23, current 46.071350/24: elapsed=16301.0us expected=16307.2us +- 81.5us, error 0.0%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.071350
last_received_ts = 46.070721
last_seq = 24
current_ts = 46.087673
current_received_ts = 46.086960
current_seq = 25
count = 2
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.071350/24, current 46.087673/25: elapsed=16322.0us expected=16307.2us +- 81.5us, error 0.1%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.087673
last_received_ts = 46.086960
last_seq = 25
current_ts = 46.103973
current_received_ts = 46.103371
current_seq = 26
count = 3
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.087673/25, current 46.103973/26: elapsed=16298.0us expected=16307.2us +- 81.5us, error 0.1%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.103973
last_received_ts = 46.103371
last_seq = 26
current_ts = 46.120285
current_received_ts = 46.119678
current_seq = 27
count = 4
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.103973/26, current 46.120285/27: elapsed=16312.0us expected=16307.2us +- 81.5us, error 0.0%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.120285
last_received_ts = 46.119678
last_seq = 27
current_ts = 46.136593
current_received_ts = 46.135975
current_seq = 28
count = 5
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.120285/27, current 46.136593/28: elapsed=16307.0us expected=16307.2us +- 81.5us, error 0.0%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.136593
last_received_ts = 46.135975
last_seq = 28
current_ts = 46.152901
current_received_ts = 46.152294
current_seq = 29
count = 6
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.136593/28, current 46.152901/29: elapsed=16309.0us expected=16307.2us +- 81.5us, error 0.0%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.152901
last_received_ts = 46.152294
last_seq = 29
current_ts = 46.169212
current_received_ts = 46.168617
current_seq = 30
count = 7
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.152901/29, current 46.169212/30: elapsed=16311.0us expected=16307.2us +- 81.5us, error 0.0%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.169212
last_received_ts = 46.168617
last_seq = 30
current_ts = 46.185516
current_received_ts = 46.184914
current_seq = 31
count = 8
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.169212/30, current 46.185516/31: elapsed=16305.0us expected=16307.2us +- 81.5us, error 0.0%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.185516
last_received_ts = 46.184914
last_seq = 31
current_ts = 46.201828
current_received_ts = 46.201183
current_seq = 32
count = 9
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.185516/31, current 46.201828/32: elapsed=16311.0us expected=16307.2us +- 81.5us, error 0.0%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.201828
last_received_ts = 46.201183
last_seq = 32
current_ts = 46.218132
current_received_ts = 46.217533
current_seq = 33
count = 10
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.201828/32, current 46.218132/33: elapsed=16305.0us expected=16307.2us +- 81.5us, error 0.0%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.218132
last_received_ts = 46.217533
last_seq = 33
current_ts = 46.234436
current_received_ts = 46.233810
current_seq = 34
count = 11
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.218132/33, current 46.234436/34: elapsed=16305.0us expected=16307.2us +- 81.5us, error 0.0%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.234436
last_received_ts = 46.233810
last_seq = 34
current_ts = 46.250755
current_received_ts = 46.250134
current_seq = 35
count = 12
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.234436/34, current 46.250755/35: elapsed=16317.0us expected=16307.2us +- 81.5us, error 0.1%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.250755
last_received_ts = 46.250134
last_seq = 35
current_ts = 46.267056
current_received_ts = 46.266453
current_seq = 36
count = 13
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.250755/35, current 46.267056/36: elapsed=16301.0us expected=16307.2us +- 81.5us, error 0.0%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.267056
last_received_ts = 46.266453
last_seq = 36
current_ts = 46.283363
current_received_ts = 46.282757
current_seq = 37
count = 14
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.267056/36, current 46.283363/37: elapsed=16309.0us expected=16307.2us +- 81.5us, error 0.0%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.283363
last_received_ts = 46.282757
last_seq = 37
current_ts = 46.299671
current_received_ts = 46.299046
current_seq = 38
count = 15
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.283363/37, current 46.299671/38: elapsed=16307.0us expected=16307.2us +- 81.5us, error 0.0%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.299671
last_received_ts = 46.299046
last_seq = 38
current_ts = 46.315979
current_received_ts = 46.315357
current_seq = 39
count = 16
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.299671/38, current 46.315979/39: elapsed=16309.0us expected=16307.2us +- 81.5us, error 0.0%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.315979
last_received_ts = 46.315357
last_seq = 39
current_ts = 46.332279
current_received_ts = 46.331638
current_seq = 40
count = 17
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.315979/39, current 46.332279/40: elapsed=16300.0us expected=16307.2us +- 81.5us, error 0.0%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.332279
last_received_ts = 46.331638
last_seq = 40
current_ts = 46.348598
current_received_ts = 46.347950
current_seq = 41
count = 18
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.332279/40, current 46.348598/41: elapsed=16318.0us expected=16307.2us +- 81.5us, error 0.1%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.348598
last_received_ts = 46.347950
last_seq = 41
current_ts = 46.364906
current_received_ts = 46.364292
current_seq = 42
count = 19
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.348598/41, current 46.364906/42: elapsed=16307.0us expected=16307.2us +- 81.5us, error 0.0%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.364906
last_received_ts = 46.364292
last_seq = 42
current_ts = 46.381210
current_received_ts = 46.380615
current_seq = 43
count = 20
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.364906/42, current 46.381210/43: elapsed=16306.0us expected=16307.2us +- 81.5us, error 0.0%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.381210
last_received_ts = 46.380615
last_seq = 43
current_ts = 46.397518
current_received_ts = 46.396915
current_seq = 44
count = 21
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.381210/43, current 46.397518/44: elapsed=16309.0us expected=16307.2us +- 81.5us, error 0.0%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.397518
last_received_ts = 46.396915
last_seq = 44
current_ts = 46.413826
current_received_ts = 46.413223
current_seq = 45
count = 22
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.397518/44, current 46.413826/45: elapsed=16307.0us expected=16307.2us +- 81.5us, error 0.0%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.413826
last_received_ts = 46.413223
last_seq = 45
current_ts = 46.430134
current_received_ts = 46.429508
current_seq = 46
count = 23
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.413826/45, current 46.430134/46: elapsed=16308.0us expected=16307.2us +- 81.5us, error 0.0%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.430134
last_received_ts = 46.429508
last_seq = 46
current_ts = 46.446445
current_received_ts = 46.445847
current_seq = 47
count = 24
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.430134/46, current 46.446445/47: elapsed=16309.0us expected=16307.2us +- 81.5us, error 0.0%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.446445
last_received_ts = 46.445847
last_seq = 47
current_ts = 46.462746
current_received_ts = 46.462139
current_seq = 48
count = 25
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.446445/47, current 46.462746/48: elapsed=16302.0us expected=16307.2us +- 81.5us, error 0.0%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.462746
last_received_ts = 46.462139
last_seq = 48
current_ts = 46.479057
current_received_ts = 46.478458
current_seq = 49
count = 26
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.462746/48, current 46.479057/49: elapsed=16313.0us expected=16307.2us +- 81.5us, error 0.0%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.479057
last_received_ts = 46.478458
last_seq = 49
current_ts = 46.495365
current_received_ts = 46.494766
current_seq = 50
count = 27
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.479057/49, current 46.495365/50: elapsed=16308.0us expected=16307.2us +- 81.5us, error 0.0%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.495365
last_received_ts = 46.494766
last_seq = 50
current_ts = 46.511673
current_received_ts = 46.511066
current_seq = 51
count = 28
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.495365/50, current 46.511673/51: elapsed=16306.0us expected=16307.2us +- 81.5us, error 0.0%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.511673
last_received_ts = 46.511066
last_seq = 51
current_ts = 46.527985
current_received_ts = 46.527382
current_seq = 52
count = 29
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.511673/51, current 46.527985/52: elapsed=16310.0us expected=16307.2us +- 81.5us, error 0.0%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.527985
last_received_ts = 46.527382
last_seq = 52
current_ts = 46.544292
current_received_ts = 46.543667
current_seq = 53
count = 30
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.527985/52, current 46.544292/53: elapsed=16308.0us expected=16307.2us +- 81.5us, error 0.0%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.544292
last_received_ts = 46.543667
last_seq = 53
current_ts = 46.560627
current_received_ts = 46.560059
current_seq = 54
count = 31
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.544292/53, current 46.560627/54: elapsed=16337.0us expected=16307.2us +- 81.5us, error 0.2%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.560627
last_received_ts = 46.560059
last_seq = 54
current_ts = 46.576988
current_received_ts = 46.576382
current_seq = 55
count = 32
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.560627/54, current 46.576988/55: elapsed=16360.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.576988
last_received_ts = 46.576382
last_seq = 55
current_ts = 46.593346
current_received_ts = 46.592743
current_seq = 56
count = 33
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.576988/55, current 46.593346/56: elapsed=16358.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.593346
last_received_ts = 46.592743
last_seq = 56
current_ts = 46.609699
current_received_ts = 46.609104
current_seq = 57
count = 34
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.593346/56, current 46.609699/57: elapsed=16355.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.609699
last_received_ts = 46.609104
last_seq = 57
current_ts = 46.626064
current_received_ts = 46.625458
current_seq = 58
count = 35
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.609699/57, current 46.626064/58: elapsed=16363.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.626064
last_received_ts = 46.625458
last_seq = 58
current_ts = 46.642426
current_received_ts = 46.641823
current_seq = 59
count = 36
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.626064/58, current 46.642426/59: elapsed=16360.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.642426
last_received_ts = 46.641823
last_seq = 59
current_ts = 46.658783
current_received_ts = 46.658157
current_seq = 60
count = 37
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.642426/59, current 46.658783/60: elapsed=16359.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.658783
last_received_ts = 46.658157
last_seq = 60
current_ts = 46.675140
current_received_ts = 46.674545
current_seq = 61
count = 38
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.658783/60, current 46.675140/61: elapsed=16359.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.675140
last_received_ts = 46.674545
last_seq = 61
current_ts = 46.691502
current_received_ts = 46.690895
current_seq = 62
count = 39
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.675140/61, current 46.691502/62: elapsed=16359.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.691502
last_received_ts = 46.690895
last_seq = 62
current_ts = 46.707859
current_received_ts = 46.707256
current_seq = 63
count = 40
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.691502/62, current 46.707859/63: elapsed=16359.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.707859
last_received_ts = 46.707256
last_seq = 63
current_ts = 46.724220
current_received_ts = 46.723618
current_seq = 64
count = 41
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.707859/63, current 46.724220/64: elapsed=16359.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.724220
last_received_ts = 46.723618
last_seq = 64
current_ts = 46.740574
current_received_ts = 46.739941
current_seq = 65
count = 42
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.724220/64, current 46.740574/65: elapsed=16355.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.740574
last_received_ts = 46.739941
last_seq = 65
current_ts = 46.756943
current_received_ts = 46.756321
current_seq = 66
count = 43
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.740574/65, current 46.756943/66: elapsed=16370.0us expected=16307.2us +- 81.5us, error 0.4%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.756943
last_received_ts = 46.756321
last_seq = 66
current_ts = 46.773296
current_received_ts = 46.772690
current_seq = 67
count = 44
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.756943/66, current 46.773296/67: elapsed=16352.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.773296
last_received_ts = 46.772690
last_seq = 67
current_ts = 46.789654
current_received_ts = 46.789055
current_seq = 68
count = 45
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.773296/67, current 46.789654/68: elapsed=16359.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.789654
last_received_ts = 46.789055
last_seq = 68
current_ts = 46.806015
current_received_ts = 46.805405
current_seq = 69
count = 46
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.789654/68, current 46.806015/69: elapsed=16359.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.806015
last_received_ts = 46.805405
last_seq = 69
current_ts = 46.822384
current_received_ts = 46.821739
current_seq = 70
count = 47
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.806015/69, current 46.822384/70: elapsed=16371.0us expected=16307.2us +- 81.5us, error 0.4%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.822384
last_received_ts = 46.821739
last_seq = 70
current_ts = 46.838730
current_received_ts = 46.838066
current_seq = 71
count = 48
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.822384/70, current 46.838730/71: elapsed=16345.0us expected=16307.2us +- 81.5us, error 0.2%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.838730
last_received_ts = 46.838066
last_seq = 71
current_ts = 46.855087
current_received_ts = 46.854431
current_seq = 72
count = 49
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.838730/71, current 46.855087/72: elapsed=16358.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.855087
last_received_ts = 46.854431
last_seq = 72
current_ts = 46.871449
current_received_ts = 46.870850
current_seq = 73
count = 50
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.855087/72, current 46.871449/73: elapsed=16362.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.871449
last_received_ts = 46.870850
last_seq = 73
current_ts = 46.887810
current_received_ts = 46.887184
current_seq = 74
count = 51
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.871449/73, current 46.887810/74: elapsed=16360.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.887810
last_received_ts = 46.887184
last_seq = 74
current_ts = 46.904163
current_received_ts = 46.903545
current_seq = 75
count = 52
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.887810/74, current 46.904163/75: elapsed=16354.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.904163
last_received_ts = 46.903545
last_seq = 75
current_ts = 46.920528
current_received_ts = 46.919926
current_seq = 76
count = 53
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.904163/75, current 46.920528/76: elapsed=16364.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.920528
last_received_ts = 46.919926
last_seq = 76
current_ts = 46.936886
current_received_ts = 46.936287
current_seq = 77
count = 54
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.920528/76, current 46.936886/77: elapsed=16359.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.936886
last_received_ts = 46.936287
last_seq = 77
current_ts = 46.953243
current_received_ts = 46.952644
current_seq = 78
count = 55
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.936886/77, current 46.953243/78: elapsed=16358.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.953243
last_received_ts = 46.952644
last_seq = 78
current_ts = 46.969604
current_received_ts = 46.969021
current_seq = 79
count = 56
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.953243/78, current 46.969604/79: elapsed=16360.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.969604
last_received_ts = 46.969021
last_seq = 79
current_ts = 46.985962
current_received_ts = 46.985359
current_seq = 80
count = 57
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.969604/79, current 46.985962/80: elapsed=16358.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 46.985962
last_received_ts = 46.985359
last_seq = 80
current_ts = 47.002319
current_received_ts = 47.001698
current_seq = 81
count = 58
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 46.985962/80, current 47.002319/81: elapsed=16358.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 47.002319
last_received_ts = 47.001698
last_seq = 81
current_ts = 47.018684
current_received_ts = 47.018089
current_seq = 82
count = 59
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 47.002319/81, current 47.018684/82: elapsed=16363.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 47.018684
last_received_ts = 47.018089
last_seq = 82
current_ts = 47.035042
current_received_ts = 47.034435
current_seq = 83
count = 60
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 47.018684/82, current 47.035042/83: elapsed=16358.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 47.035042
last_received_ts = 47.034435
last_seq = 83
current_ts = 47.051403
current_received_ts = 47.050797
current_seq = 84
count = 61
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 47.035042/83, current 47.051403/84: elapsed=16360.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 47.051403
last_received_ts = 47.050797
last_seq = 84
current_ts = 47.067760
current_received_ts = 47.067154
current_seq = 85
count = 62
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 47.051403/84, current 47.067760/85: elapsed=16358.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 47.067760
last_received_ts = 47.067154
last_seq = 85
current_ts = 47.084122
current_received_ts = 47.083515
current_seq = 86
count = 63
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 47.067760/85, current 47.084122/86: elapsed=16362.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 47.084122
last_received_ts = 47.083515
last_seq = 86
current_ts = 47.100479
current_received_ts = 47.099876
current_seq = 87
count = 64
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 47.084122/86, current 47.100479/87: elapsed=16358.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 47.100479
last_received_ts = 47.099876
last_seq = 87
current_ts = 47.116837
current_received_ts = 47.116207
current_seq = 88
count = 65
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 47.100479/87, current 47.116837/88: elapsed=16357.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 47.116837
last_received_ts = 47.116207
last_seq = 88
current_ts = 47.133205
current_received_ts = 47.132561
current_seq = 89
count = 66
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 47.116837/88, current 47.133205/89: elapsed=16368.0us expected=16307.2us +- 81.5us, error 0.4%
(kms_flip:1493) DEBUG: name = flip
last_ts = 47.133205
last_received_ts = 47.132561
last_seq = 89
current_ts = 47.149555
current_received_ts = 47.148960
current_seq = 90
count = 67
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 47.133205/89, current 47.149555/90: elapsed=16352.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 47.149555
last_received_ts = 47.148960
last_seq = 90
current_ts = 47.165916
current_received_ts = 47.165318
current_seq = 91
count = 68
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 47.149555/90, current 47.165916/91: elapsed=16359.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 47.165916
last_received_ts = 47.165318
last_seq = 91
current_ts = 47.182278
current_received_ts = 47.181679
current_seq = 92
count = 69
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 47.165916/91, current 47.182278/92: elapsed=16361.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 47.182278
last_received_ts = 47.181679
last_seq = 92
current_ts = 47.198631
current_received_ts = 47.198051
current_seq = 93
count = 70
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 47.182278/92, current 47.198631/93: elapsed=16356.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 47.198631
last_received_ts = 47.198051
last_seq = 93
current_ts = 47.214993
current_received_ts = 47.214390
current_seq = 94
count = 71
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 47.198631/93, current 47.214993/94: elapsed=16359.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 47.214993
last_received_ts = 47.214390
last_seq = 94
current_ts = 47.231350
current_received_ts = 47.230755
current_seq = 95
count = 72
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 47.214993/94, current 47.231350/95: elapsed=16359.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 47.231350
last_received_ts = 47.230755
last_seq = 95
current_ts = 47.247711
current_received_ts = 47.247093
current_seq = 96
count = 73
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 47.231350/95, current 47.247711/96: elapsed=16359.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 47.247711
last_received_ts = 47.247093
last_seq = 96
current_ts = 47.264069
current_received_ts = 47.263481
current_seq = 97
count = 74
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 47.247711/96, current 47.264069/97: elapsed=16359.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 47.264069
last_received_ts = 47.263481
last_seq = 97
current_ts = 47.280430
current_received_ts = 47.279823
current_seq = 98
count = 75
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 47.264069/97, current 47.280430/98: elapsed=16360.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 47.280430
last_received_ts = 47.279823
last_seq = 98
current_ts = 47.296799
current_received_ts = 47.296131
current_seq = 99
count = 76
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 47.280430/98, current 47.296799/99: elapsed=16369.0us expected=16307.2us +- 81.5us, error 0.4%
(kms_flip:1493) DEBUG: name = flip
last_ts = 47.296799
last_received_ts = 47.296131
last_seq = 99
current_ts = 47.313148
current_received_ts = 47.312546
current_seq = 100
count = 77
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 47.296799/99, current 47.313148/100: elapsed=16349.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 47.313148
last_received_ts = 47.312546
last_seq = 100
current_ts = 47.329506
current_received_ts = 47.328903
current_seq = 101
count = 78
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 47.313148/100, current 47.329506/101: elapsed=16358.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 47.329506
last_received_ts = 47.328903
last_seq = 101
current_ts = 47.345863
current_received_ts = 47.345264
current_seq = 102
count = 79
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 47.329506/101, current 47.345863/102: elapsed=16360.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 47.345863
last_received_ts = 47.345264
last_seq = 102
current_ts = 47.362225
current_received_ts = 47.361637
current_seq = 103
count = 80
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 47.345863/102, current 47.362225/103: elapsed=16360.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 47.362225
last_received_ts = 47.361637
last_seq = 103
current_ts = 47.378582
current_received_ts = 47.377983
current_seq = 104
count = 81
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 47.362225/103, current 47.378582/104: elapsed=16358.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 47.378582
last_received_ts = 47.377983
last_seq = 104
current_ts = 47.394943
current_received_ts = 47.394344
current_seq = 105
count = 82
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 47.378582/104, current 47.394943/105: elapsed=16359.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 47.394943
last_received_ts = 47.394344
last_seq = 105
current_ts = 47.411301
current_received_ts = 47.410702
current_seq = 106
count = 83
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 47.394943/105, current 47.411301/106: elapsed=16359.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 47.411301
last_received_ts = 47.410702
last_seq = 106
current_ts = 47.427662
current_received_ts = 47.427082
current_seq = 107
count = 84
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 47.411301/106, current 47.427662/107: elapsed=16360.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 47.427662
last_received_ts = 47.427082
last_seq = 107
current_ts = 47.444019
current_received_ts = 47.443398
current_seq = 108
count = 85
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 47.427662/107, current 47.444019/108: elapsed=16359.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 47.444019
last_received_ts = 47.443398
last_seq = 108
current_ts = 47.460388
current_received_ts = 47.459743
current_seq = 109
count = 86
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 47.444019/108, current 47.460388/109: elapsed=16370.0us expected=16307.2us +- 81.5us, error 0.4%
(kms_flip:1493) DEBUG: name = flip
last_ts = 47.460388
last_received_ts = 47.459743
last_seq = 109
current_ts = 47.476734
current_received_ts = 47.476120
current_seq = 110
count = 87
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 47.460388/109, current 47.476734/110: elapsed=16346.0us expected=16307.2us +- 81.5us, error 0.2%
(kms_flip:1493) DEBUG: name = flip
last_ts = 47.476734
last_received_ts = 47.476120
last_seq = 110
current_ts = 47.493099
current_received_ts = 47.492512
current_seq = 111
count = 88
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 47.476734/110, current 47.493099/111: elapsed=16363.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 47.493099
last_received_ts = 47.492512
last_seq = 111
current_ts = 47.509457
current_received_ts = 47.508850
current_seq = 112
count = 89
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 47.493099/111, current 47.509457/112: elapsed=16357.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 47.509457
last_received_ts = 47.508850
last_seq = 112
current_ts = 47.525814
current_received_ts = 47.525211
current_seq = 113
count = 90
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 47.509457/112, current 47.525814/113: elapsed=16359.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 47.525814
last_received_ts = 47.525211
last_seq = 113
current_ts = 47.542175
current_received_ts = 47.541573
current_seq = 114
count = 91
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 47.525814/113, current 47.542175/114: elapsed=16360.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 47.542175
last_received_ts = 47.541573
last_seq = 114
current_ts = 47.558540
current_received_ts = 47.557941
current_seq = 115
count = 92
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 47.542175/114, current 47.558540/115: elapsed=16364.0us expected=16307.2us +- 81.5us, error 0.3%
(kms_flip:1493) DEBUG: name = flip
last_ts = 47.558540
last_received_ts = 47.557941
last_seq = 115
current_ts = 47.574947
current_received_ts = 47.574348
current_seq = 116
count = 93
seq_step = 1
(kms_flip:1493) DEBUG: flip ts/seq: last 47.558540/115, current 47.574947/116: elapsed=16408.0us expected=16307.2us +- 81.5us, error 0.6%
(kms_flip:1493) DEBUG: inconsistent flip ts/seq: last 47.558540/115, current 47.574947/116: elapsed=16408.0us expected=16307.2us
(kms_flip:1493) DEBUG: expected 244400, counted 93, encoder type 1
(kms_flip:1493) DEBUG: dropped frames, expected 244400, counted 93, encoder type 1
(kms_flip:1493) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:1493) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
(kms_flip:1493) igt_core-INFO: Stack trace:
(kms_flip:1493) igt_core-INFO:   #0 ../lib/igt_core.c:2051 __igt_fail_assert()
(kms_flip:1493) igt_core-INFO:   #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0()
(kms_flip:1493) igt_core-INFO:   #2 ../tests/kms_flip.c:1845 run_test()
(kms_flip:1493) igt_core-INFO:   #3 ../tests/kms_flip.c:2078 __igt_unique____real_main2001()
(kms_flip:1493) igt_core-INFO:   #4 ../tests/kms_flip.c:2001 main()
(kms_flip:1493) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:1493) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:1493) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest B-VGA1: FAIL (5.074s)
Dmesg
<6> [45.724153] [IGT] kms_flip: starting dynamic subtest B-VGA1
<7> [45.724628] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [45.724693] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [45.757273] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:47:pipe A]
<7> [45.757433] 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> [45.757850] 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> [45.758143] 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> [45.758456] 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> [45.758788] 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> [45.759149] 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> [45.759420] 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> [45.759759] 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> [45.760161] 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> [45.760502] 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> [45.760963] 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> [45.761168] 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> [45.761381] 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> [45.761592] 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> [45.761805] 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> [45.762027] 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> [45.762214] 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> [45.762397] 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> [45.762580] 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> [45.762762] 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> [45.762992] 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> [45.763177] 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> [45.763360] 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> [45.763542] 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> [45.763724] 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> [45.763906] 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> [45.764097] 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> [45.764279] 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> [45.764461] 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> [45.764642] 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> [45.764823] 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> [45.765034] 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> [45.765217] 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> [45.765399] 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> [45.765580] 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> [45.765763] 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> [45.765944] 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> [45.766133] 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> [45.766314] 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> [45.766496] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:47:pipe A] fastset requirement not met, forcing full modeset
<7> [45.766678] i915 0000:00:02.0: [drm:intel_unreference_shared_dpll_crtc [i915]] [CRTC:47:pipe A] releasing PCH DPLL A
<7> [45.766875] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 400000 kHz, actual 400000 kHz
<7> [45.767078] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [45.767278] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:47:pipe A] enable: no [modeset]
<7> [45.767491] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:primary A] fb: [NOFB], visible: no
<7> [45.767860] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [45.770968] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found
<7> [45.771160] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found
<7> [45.771336] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found
<7> [45.787672] 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> [45.788047] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disabling PCH DPLL A
<7> [45.788548] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:65:CRT]
<7> [45.788881] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:66:HDMI D]
<7> [45.789102] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:DP D]
<7> [45.789293] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:64:VGA-1]
<7> [45.789502] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:47:pipe A]
<7> [45.789803] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:63:pipe B]
<7> [45.789919] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:63:pipe B]
<7> [45.789935] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:64:VGA-1]
<7> [45.789990] 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> [45.790211] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:63:pipe B] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [45.790389] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe B, lanes 1
<7> [45.790560] 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> [45.790755] 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> [45.790938] 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> [45.791119] 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> [45.791301] 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> [45.791483] 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> [45.791673] 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> [45.791885] 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> [45.792097] 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> [45.792309] 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> [45.792522] 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> [45.792755] 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> [45.792943] 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> [45.793127] 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> [45.793310] 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> [45.793492] 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> [45.793675] 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> [45.793866] 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> [45.794048] 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> [45.794230] 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> [45.794411] 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> [45.794593] 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> [45.794793] 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> [45.794975] 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> [45.795156] 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> [45.795338] 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> [45.795519] 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> [45.795700] 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> [45.795889] 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> [45.796071] 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> [45.796252] 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> [45.796460] 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> [45.796672] 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> [45.796902] 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> [45.797089] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in dpll_hw_state
<7> [45.797271] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [45.797453] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0x0, dpll_md: 0x0, fp0: 0x0, fp1: 0x0
<7> [45.797646] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [45.797865] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [45.798050] 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> [45.798233] 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> [45.798415] 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> [45.798597] 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> [45.798779] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:63:pipe B] fastset requirement not met, forcing full modeset
<7> [45.799008] 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> [45.799194] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 400000 kHz, actual 400000 kHz
<7> [45.799378] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [45.799561] i915 0000:00:02.0: [drm:intel_find_shared_dpll [i915]] [CRTC:63:pipe B] allocated PCH DPLL A
<7> [45.799738] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc [i915]] [CRTC:63:pipe B] reserving PCH DPLL A
<7> [45.799926] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:63:pipe B] enable: yes [modeset]
<7> [45.800121] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB
<7> [45.800334] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: B, pipe bpp: 24, dithering: 0
<7> [45.800546] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [45.800758] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [45.800978] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [45.801165] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [45.801349] 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> [45.801533] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0
<7> [45.801715] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [45.801917] 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> [45.802101] 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> [45.802284] 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> [45.802468] 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> [45.802651] 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> [45.802834] 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> [45.803026] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 64800, pipe src: 1024x768+0+0, pixel rate 64800
<7> [45.803209] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0
<7> [45.803391] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [45.803573] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [45.803780] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [45.804012] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [45.804198] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [45.804381] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [45.804564] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [45.804746] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [45.804928] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [45.805118] 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> [45.805328] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [45.805527] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0
<7> [45.806833] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:65:CRT]
<7> [45.807051] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:66:HDMI D]
<7> [45.807237] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:DP D]
<7> [45.808026] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe B
<7> [45.809086] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100
<7> [45.809269] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done.
<7> [45.810134] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x600
<7> [45.810348] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done.
<7> [45.810538] i915 0000:00:02.0: [drm:ilk_pch_enable [i915]] FDI train done.
<7> [45.810775] 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> [45.810979] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling PCH DPLL A
<7> [45.862927] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:64:VGA-1]
<7> [45.863312] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:63:pipe B]
<6> [50.798261] [IGT] kms_flip: finished subtest B-VGA1, FAIL
Created at 2024-10-18 12:50:50