Results for igt@kms_flip@flip-vs-absolute-wf_vblank-interruptible@a-vga1

Result: Fail

integration-manifest git-log-oneline i915_display_info4 igt_runner4 runtimes4 results4.json results4-i915-load.json guc_logs4.tar i915_display_info_post_exec4 boot4 dmesg4

DetailValue
Duration 19.07 seconds
Hostname 
shard-snb6
Igt-Version 
IGT-Version: 2.3-gc807f7fde (x86_64) (Linux: 7.0.0-rc1-CI_DRM_18022-g45a3045fc0dc+ x86_64)
Out 
Using IGT_SRANDOM=1771882041 for randomisation
Opened device: /dev/dri/card0
Using monotonic timestamps
Starting subtest: flip-vs-absolute-wf_vblank-interruptible
Starting dynamic subtest: A-VGA1
  1024x768: 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa 
Expected frametime: 16666us; measured 16717.4us +- 10.721us accuracy 0.19%
vblank interval differs from modeline! expected 16665.6us, measured 16717us +- 10.721us, difference 51.8us (4.8 sigma)
Event flip: expected 454, counted 450, passrate = 66.67%, encoder type 1
Event vblank: expected 454, counted 450, passrate = 64.44%, encoder type 1
Expected frametime: 16666us; measured 16987.2us +- 17.112us accuracy 0.30%
vblank interval differs from modeline! expected 16665.6us, measured 16987us +- 17.112us, difference 321.6us (18.8 sigma)
Event flip: expected 447, counted 450, passrate = 40.00%, encoder type 1
Event vblank: expected 447, counted 450, passrate = 37.78%, encoder type 1
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1887 run_test()
  #3 ../tests/kms_flip.c:2156 __igt_unique____real_main2043()
  #4 ../tests/kms_flip.c:2043 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest A-VGA1: FAIL (19.065s)
Err 
Starting subtest: flip-vs-absolute-wf_vblank-interruptible
Starting dynamic subtest: A-VGA1
(kms_flip:2704) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1707:
(kms_flip:2704) CRITICAL: Failed assertion: !retried
(kms_flip:2704) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest A-VGA1 failed.
**** DEBUG ****
(kms_flip:2704) DEBUG: Retrying without a hotplug event
(kms_flip:2704) DEBUG: No stale events found
(kms_flip:2704) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:2704) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:2704) INFO: Expected frametime: 16666us; measured 16987.2us +- 17.112us accuracy 0.30%
(kms_flip:2704) INFO: vblank interval differs from modeline! expected 16665.6us, measured 16987us +- 17.112us, difference 321.6us (18.8 sigma)
(kms_flip:2704) DEBUG: name = flip
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 342.946320
current_received_ts = 342.945679
current_seq = 14710
count = 0
seq_step = 1
(kms_flip:2704) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 343.098999
current_received_ts = 343.098389
current_seq = 14719
count = 0
seq_step = 10
(kms_flip:2704) DEBUG: name = flip
last_ts = 342.946320
last_received_ts = 342.945679
last_seq = 14710
current_ts = 343.115936
current_received_ts = 343.115326
current_seq = 14720
count = 1
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 342.946320/14710, current 343.115936/14720: elapsed=169631.0us expected=169871.9us +- 849.4us, error 0.1%
(kms_flip:2704) DEBUG: name = vblank
last_ts = 343.098999
last_received_ts = 343.098389
last_seq = 14719
current_ts = 343.268646
current_received_ts = 343.267944
current_seq = 14729
count = 1
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 343.098999/14719, current 343.268646/14729: elapsed=169647.0us expected=169871.9us +- 849.4us, error 0.1%
(kms_flip:2704) DEBUG: name = flip
last_ts = 343.115936
last_received_ts = 343.115326
last_seq = 14720
current_ts = 343.285583
current_received_ts = 343.284882
current_seq = 14730
count = 2
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 343.115936/14720, current 343.285583/14730: elapsed=169637.0us expected=169871.9us +- 849.4us, error 0.1%
(kms_flip:2704) DEBUG: name = vblank
last_ts = 343.268646
last_received_ts = 343.267944
last_seq = 14729
current_ts = 343.438263
current_received_ts = 343.437653
current_seq = 14739
count = 2
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 343.268646/14729, current 343.438263/14739: elapsed=169621.0us expected=169871.9us +- 849.4us, error 0.1%
(kms_flip:2704) DEBUG: name = flip
last_ts = 343.285583
last_received_ts = 343.284882
last_seq = 14730
current_ts = 343.455231
current_received_ts = 343.454590
current_seq = 14740
count = 3
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 343.285583/14730, current 343.455231/14740: elapsed=169646.0us expected=169871.9us +- 849.4us, error 0.1%
(kms_flip:2704) DEBUG: name = vblank
last_ts = 343.438263
last_received_ts = 343.437653
last_seq = 14739
current_ts = 343.607880
current_received_ts = 343.607239
current_seq = 14749
count = 3
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 343.438263/14739, current 343.607880/14749: elapsed=169640.0us expected=169871.9us +- 849.4us, error 0.1%
(kms_flip:2704) DEBUG: name = flip
last_ts = 343.455231
last_received_ts = 343.454590
last_seq = 14740
current_ts = 343.624847
current_received_ts = 343.624176
current_seq = 14750
count = 4
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 343.455231/14740, current 343.624847/14750: elapsed=169626.0us expected=169871.9us +- 849.4us, error 0.1%
(kms_flip:2704) DEBUG: name = vblank
last_ts = 343.607880
last_received_ts = 343.607239
last_seq = 14749
current_ts = 343.777527
current_received_ts = 343.776917
current_seq = 14759
count = 4
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 343.607880/14749, current 343.777527/14759: elapsed=169624.0us expected=169871.9us +- 849.4us, error 0.1%
(kms_flip:2704) DEBUG: name = flip
last_ts = 343.624847
last_received_ts = 343.624176
last_seq = 14750
current_ts = 343.794464
current_received_ts = 343.793884
current_seq = 14760
count = 5
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 343.624847/14750, current 343.794464/14760: elapsed=169621.0us expected=169871.9us +- 849.4us, error 0.1%
(kms_flip:2704) DEBUG: name = vblank
last_ts = 343.777527
last_received_ts = 343.776917
last_seq = 14759
current_ts = 343.946960
current_received_ts = 343.946320
current_seq = 14769
count = 5
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 343.777527/14759, current 343.946960/14769: elapsed=169458.0us expected=169871.9us +- 849.4us, error 0.2%
(kms_flip:2704) DEBUG: name = flip
last_ts = 343.794464
last_received_ts = 343.793884
last_seq = 14760
current_ts = 343.963898
current_received_ts = 343.963257
current_seq = 14770
count = 6
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 343.794464/14760, current 343.963898/14770: elapsed=169413.0us expected=169871.9us +- 849.4us, error 0.3%
(kms_flip:2704) DEBUG: name = vblank
last_ts = 343.946960
last_received_ts = 343.946320
last_seq = 14769
current_ts = 344.116302
current_received_ts = 344.115692
current_seq = 14779
count = 6
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 343.946960/14769, current 344.116302/14779: elapsed=169321.0us expected=169871.9us +- 849.4us, error 0.3%
(kms_flip:2704) DEBUG: name = flip
last_ts = 343.963898
last_received_ts = 343.963257
last_seq = 14770
current_ts = 344.133209
current_received_ts = 344.132629
current_seq = 14780
count = 7
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 343.963898/14770, current 344.133209/14780: elapsed=169330.0us expected=169871.9us +- 849.4us, error 0.3%
(kms_flip:2704) DEBUG: name = vblank
last_ts = 344.116302
last_received_ts = 344.115692
last_seq = 14779
current_ts = 344.285645
current_received_ts = 344.284973
current_seq = 14789
count = 7
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 344.116302/14779, current 344.285645/14789: elapsed=169337.0us expected=169871.9us +- 849.4us, error 0.3%
(kms_flip:2704) DEBUG: name = flip
last_ts = 344.133209
last_received_ts = 344.132629
last_seq = 14780
current_ts = 344.302551
current_received_ts = 344.301941
current_seq = 14790
count = 8
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 344.133209/14780, current 344.302551/14790: elapsed=169335.0us expected=169871.9us +- 849.4us, error 0.3%
(kms_flip:2704) DEBUG: name = vblank
last_ts = 344.285645
last_received_ts = 344.284973
last_seq = 14789
current_ts = 344.454956
current_received_ts = 344.454346
current_seq = 14799
count = 8
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 344.285645/14789, current 344.454956/14799: elapsed=169323.0us expected=169871.9us +- 849.4us, error 0.3%
(kms_flip:2704) DEBUG: name = flip
last_ts = 344.302551
last_received_ts = 344.301941
last_seq = 14790
current_ts = 344.471893
current_received_ts = 344.471283
current_seq = 14800
count = 9
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 344.302551/14790, current 344.471893/14800: elapsed=169348.0us expected=169871.9us +- 849.4us, error 0.3%
(kms_flip:2704) DEBUG: name = vblank
last_ts = 344.454956
last_received_ts = 344.454346
last_seq = 14799
current_ts = 344.624298
current_received_ts = 344.623688
current_seq = 14809
count = 9
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 344.454956/14799, current 344.624298/14809: elapsed=169332.0us expected=169871.9us +- 849.4us, error 0.3%
(kms_flip:2704) DEBUG: name = flip
last_ts = 344.471893
last_received_ts = 344.471283
last_seq = 14800
current_ts = 344.641205
current_received_ts = 344.640625
current_seq = 14810
count = 10
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 344.471893/14800, current 344.641205/14810: elapsed=169315.0us expected=169871.9us +- 849.4us, error 0.3%
(kms_flip:2704) DEBUG: name = vblank
last_ts = 344.624298
last_received_ts = 344.623688
last_seq = 14809
current_ts = 344.793640
current_received_ts = 344.793060
current_seq = 14819
count = 10
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 344.624298/14809, current 344.793640/14819: elapsed=169353.0us expected=169871.9us +- 849.4us, error 0.3%
(kms_flip:2704) DEBUG: name = flip
last_ts = 344.641205
last_received_ts = 344.640625
last_seq = 14810
current_ts = 344.810547
current_received_ts = 344.809967
current_seq = 14820
count = 11
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 344.641205/14810, current 344.810547/14820: elapsed=169339.0us expected=169871.9us +- 849.4us, error 0.3%
(kms_flip:2704) DEBUG: name = vblank
last_ts = 344.793640
last_received_ts = 344.793060
last_seq = 14819
current_ts = 344.962769
current_received_ts = 344.962158
current_seq = 14829
count = 11
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 344.793640/14819, current 344.962769/14829: elapsed=169134.0us expected=169871.9us +- 849.4us, error 0.4%
(kms_flip:2704) DEBUG: name = flip
last_ts = 344.810547
last_received_ts = 344.809967
last_seq = 14820
current_ts = 344.979675
current_received_ts = 344.979126
current_seq = 14830
count = 12
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 344.810547/14820, current 344.979675/14830: elapsed=169109.0us expected=169871.9us +- 849.4us, error 0.4%
(kms_flip:2704) DEBUG: name = vblank
last_ts = 344.962769
last_received_ts = 344.962158
last_seq = 14829
current_ts = 345.131836
current_received_ts = 345.131195
current_seq = 14839
count = 12
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 344.962769/14829, current 345.131836/14839: elapsed=169070.0us expected=169871.9us +- 849.4us, error 0.5%
(kms_flip:2704) DEBUG: name = flip
last_ts = 344.979675
last_received_ts = 344.979126
last_seq = 14830
current_ts = 345.148743
current_received_ts = 345.148071
current_seq = 14840
count = 13
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 344.979675/14830, current 345.148743/14840: elapsed=169088.0us expected=169871.9us +- 849.4us, error 0.5%
(kms_flip:2704) DEBUG: name = vblank
last_ts = 345.131836
last_received_ts = 345.131195
last_seq = 14839
current_ts = 345.300903
current_received_ts = 345.300232
current_seq = 14849
count = 13
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 345.131836/14839, current 345.300903/14849: elapsed=169066.0us expected=169871.9us +- 849.4us, error 0.5%
(kms_flip:2704) DEBUG: name = flip
last_ts = 345.148743
last_received_ts = 345.148071
last_seq = 14840
current_ts = 345.317841
current_received_ts = 345.317139
current_seq = 14850
count = 14
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 345.148743/14840, current 345.317841/14850: elapsed=169070.0us expected=169871.9us +- 849.4us, error 0.5%
(kms_flip:2704) DEBUG: name = vblank
last_ts = 345.300903
last_received_ts = 345.300232
last_seq = 14849
current_ts = 345.469971
current_received_ts = 345.469330
current_seq = 14859
count = 14
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 345.300903/14849, current 345.469971/14859: elapsed=169070.0us expected=169871.9us +- 849.4us, error 0.5%
(kms_flip:2704) DEBUG: name = flip
last_ts = 345.317841
last_received_ts = 345.317139
last_seq = 14850
current_ts = 345.486908
current_received_ts = 345.486267
current_seq = 14860
count = 15
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 345.317841/14850, current 345.486908/14860: elapsed=169081.0us expected=169871.9us +- 849.4us, error 0.5%
(kms_flip:2704) DEBUG: name = vblank
last_ts = 345.469971
last_received_ts = 345.469330
last_seq = 14859
current_ts = 345.639069
current_received_ts = 345.638397
current_seq = 14869
count = 15
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 345.469971/14859, current 345.639069/14869: elapsed=169074.0us expected=169871.9us +- 849.4us, error 0.5%
(kms_flip:2704) DEBUG: name = flip
last_ts = 345.486908
last_received_ts = 345.486267
last_seq = 14860
current_ts = 345.655975
current_received_ts = 345.655334
current_seq = 14870
count = 16
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 345.486908/14860, current 345.655975/14870: elapsed=169059.0us expected=169871.9us +- 849.4us, error 0.5%
(kms_flip:2704) DEBUG: name = vblank
last_ts = 345.639069
last_received_ts = 345.638397
last_seq = 14869
current_ts = 345.808136
current_received_ts = 345.807495
current_seq = 14879
count = 16
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 345.639069/14869, current 345.808136/14879: elapsed=169081.0us expected=169871.9us +- 849.4us, error 0.5%
(kms_flip:2704) DEBUG: name = flip
last_ts = 345.655975
last_received_ts = 345.655334
last_seq = 14870
current_ts = 345.825043
current_received_ts = 345.824402
current_seq = 14880
count = 17
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 345.655975/14870, current 345.825043/14880: elapsed=169087.0us expected=169871.9us +- 849.4us, error 0.5%
(kms_flip:2704) DEBUG: name = vblank
last_ts = 345.808136
last_received_ts = 345.807495
last_seq = 14879
current_ts = 345.977020
current_received_ts = 345.976410
current_seq = 14889
count = 17
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 345.808136/14879, current 345.977020/14889: elapsed=168891.0us expected=169871.9us +- 849.4us, error 0.6%
(kms_flip:2704) DEBUG: inconsistent vblank ts/seq: last 345.808136/14879, current 345.977020/14889: elapsed=168891.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = flip
last_ts = 345.825043
last_received_ts = 345.824402
last_seq = 14880
current_ts = 345.993896
current_received_ts = 345.993256
current_seq = 14890
count = 18
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 345.825043/14880, current 345.993896/14890: elapsed=168843.0us expected=169871.9us +- 849.4us, error 0.6%
(kms_flip:2704) DEBUG: inconsistent flip ts/seq: last 345.825043/14880, current 345.993896/14890: elapsed=168843.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = vblank
last_ts = 345.977020
last_received_ts = 345.976410
last_seq = 14889
current_ts = 346.145874
current_received_ts = 346.145142
current_seq = 14899
count = 18
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 345.977020/14889, current 346.145874/14899: elapsed=168840.0us expected=169871.9us +- 849.4us, error 0.6%
(kms_flip:2704) DEBUG: inconsistent vblank ts/seq: last 345.977020/14889, current 346.145874/14899: elapsed=168840.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = flip
last_ts = 345.993896
last_received_ts = 345.993256
last_seq = 14890
current_ts = 346.162750
current_received_ts = 346.162140
current_seq = 14900
count = 19
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 345.993896/14890, current 346.162750/14900: elapsed=168862.0us expected=169871.9us +- 849.4us, error 0.6%
(kms_flip:2704) DEBUG: inconsistent flip ts/seq: last 345.993896/14890, current 346.162750/14900: elapsed=168862.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = vblank
last_ts = 346.145874
last_received_ts = 346.145142
last_seq = 14899
current_ts = 346.314697
current_received_ts = 346.314087
current_seq = 14909
count = 19
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 346.145874/14899, current 346.314697/14909: elapsed=168843.0us expected=169871.9us +- 849.4us, error 0.6%
(kms_flip:2704) DEBUG: inconsistent vblank ts/seq: last 346.145874/14899, current 346.314697/14909: elapsed=168843.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = flip
last_ts = 346.162750
last_received_ts = 346.162140
last_seq = 14900
current_ts = 346.331604
current_received_ts = 346.330994
current_seq = 14910
count = 20
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 346.162750/14900, current 346.331604/14910: elapsed=168836.0us expected=169871.9us +- 849.4us, error 0.6%
(kms_flip:2704) DEBUG: inconsistent flip ts/seq: last 346.162750/14900, current 346.331604/14910: elapsed=168836.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = vblank
last_ts = 346.314697
last_received_ts = 346.314087
last_seq = 14909
current_ts = 346.483551
current_received_ts = 346.482941
current_seq = 14919
count = 20
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 346.314697/14909, current 346.483551/14919: elapsed=168844.0us expected=169871.9us +- 849.4us, error 0.6%
(kms_flip:2704) DEBUG: inconsistent vblank ts/seq: last 346.314697/14909, current 346.483551/14919: elapsed=168844.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = flip
last_ts = 346.331604
last_received_ts = 346.330994
last_seq = 14910
current_ts = 346.500427
current_received_ts = 346.499847
current_seq = 14920
count = 21
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 346.331604/14910, current 346.500427/14920: elapsed=168843.0us expected=169871.9us +- 849.4us, error 0.6%
(kms_flip:2704) DEBUG: inconsistent flip ts/seq: last 346.331604/14910, current 346.500427/14920: elapsed=168843.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = vblank
last_ts = 346.483551
last_received_ts = 346.482941
last_seq = 14919
current_ts = 346.652405
current_received_ts = 346.651764
current_seq = 14929
count = 21
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 346.483551/14919, current 346.652405/14929: elapsed=168846.0us expected=169871.9us +- 849.4us, error 0.6%
(kms_flip:2704) DEBUG: inconsistent vblank ts/seq: last 346.483551/14919, current 346.652405/14929: elapsed=168846.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = flip
last_ts = 346.500427
last_received_ts = 346.499847
last_seq = 14920
current_ts = 346.669281
current_received_ts = 346.668640
current_seq = 14930
count = 22
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 346.500427/14920, current 346.669281/14930: elapsed=168854.0us expected=169871.9us +- 849.4us, error 0.6%
(kms_flip:2704) DEBUG: inconsistent flip ts/seq: last 346.500427/14920, current 346.669281/14930: elapsed=168854.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = vblank
last_ts = 346.652405
last_received_ts = 346.651764
last_seq = 14929
current_ts = 346.821259
current_received_ts = 346.820648
current_seq = 14939
count = 22
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 346.652405/14929, current 346.821259/14939: elapsed=168850.0us expected=169871.9us +- 849.4us, error 0.6%
(kms_flip:2704) DEBUG: inconsistent vblank ts/seq: last 346.652405/14929, current 346.821259/14939: elapsed=168850.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = flip
last_ts = 346.669281
last_received_ts = 346.668640
last_seq = 14930
current_ts = 346.838135
current_received_ts = 346.837585
current_seq = 14940
count = 23
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 346.669281/14930, current 346.838135/14940: elapsed=168836.0us expected=169871.9us +- 849.4us, error 0.6%
(kms_flip:2704) DEBUG: inconsistent flip ts/seq: last 346.669281/14930, current 346.838135/14940: elapsed=168836.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = vblank
last_ts = 346.821259
last_received_ts = 346.820648
last_seq = 14939
current_ts = 346.989899
current_received_ts = 346.989288
current_seq = 14949
count = 23
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 346.821259/14939, current 346.989899/14949: elapsed=168663.0us expected=169871.9us +- 849.4us, error 0.7%
(kms_flip:2704) DEBUG: inconsistent vblank ts/seq: last 346.821259/14939, current 346.989899/14949: elapsed=168663.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = flip
last_ts = 346.838135
last_received_ts = 346.837585
last_seq = 14940
current_ts = 347.006775
current_received_ts = 347.006134
current_seq = 14950
count = 24
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 346.838135/14940, current 347.006775/14950: elapsed=168656.0us expected=169871.9us +- 849.4us, error 0.7%
(kms_flip:2704) DEBUG: inconsistent flip ts/seq: last 346.838135/14940, current 347.006775/14950: elapsed=168656.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = vblank
last_ts = 346.989899
last_received_ts = 346.989288
last_seq = 14949
current_ts = 347.158569
current_received_ts = 347.157898
current_seq = 14959
count = 24
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 346.989899/14949, current 347.158569/14959: elapsed=168656.0us expected=169871.9us +- 849.4us, error 0.7%
(kms_flip:2704) DEBUG: inconsistent vblank ts/seq: last 346.989899/14949, current 347.158569/14959: elapsed=168656.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = flip
last_ts = 347.006775
last_received_ts = 347.006134
last_seq = 14950
current_ts = 347.175415
current_received_ts = 347.174805
current_seq = 14960
count = 25
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 347.006775/14950, current 347.175415/14960: elapsed=168641.0us expected=169871.9us +- 849.4us, error 0.7%
(kms_flip:2704) DEBUG: inconsistent flip ts/seq: last 347.006775/14950, current 347.175415/14960: elapsed=168641.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = vblank
last_ts = 347.158569
last_received_ts = 347.157898
last_seq = 14959
current_ts = 347.327209
current_received_ts = 347.326599
current_seq = 14969
count = 25
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 347.158569/14959, current 347.327209/14969: elapsed=168639.0us expected=169871.9us +- 849.4us, error 0.7%
(kms_flip:2704) DEBUG: inconsistent vblank ts/seq: last 347.158569/14959, current 347.327209/14969: elapsed=168639.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = flip
last_ts = 347.175415
last_received_ts = 347.174805
last_seq = 14960
current_ts = 347.344086
current_received_ts = 347.343475
current_seq = 14970
count = 26
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 347.175415/14960, current 347.344086/14970: elapsed=168664.0us expected=169871.9us +- 849.4us, error 0.7%
(kms_flip:2704) DEBUG: inconsistent flip ts/seq: last 347.175415/14960, current 347.344086/14970: elapsed=168664.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = vblank
last_ts = 347.327209
last_received_ts = 347.326599
last_seq = 14969
current_ts = 347.495880
current_received_ts = 347.495239
current_seq = 14979
count = 26
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 347.327209/14969, current 347.495880/14979: elapsed=168659.0us expected=169871.9us +- 849.4us, error 0.7%
(kms_flip:2704) DEBUG: inconsistent vblank ts/seq: last 347.327209/14969, current 347.495880/14979: elapsed=168659.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = flip
last_ts = 347.344086
last_received_ts = 347.343475
last_seq = 14970
current_ts = 347.512726
current_received_ts = 347.512115
current_seq = 14980
count = 27
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 347.344086/14970, current 347.512726/14980: elapsed=168643.0us expected=169871.9us +- 849.4us, error 0.7%
(kms_flip:2704) DEBUG: inconsistent flip ts/seq: last 347.344086/14970, current 347.512726/14980: elapsed=168643.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = vblank
last_ts = 347.495880
last_received_ts = 347.495239
last_seq = 14979
current_ts = 347.664490
current_received_ts = 347.663910
current_seq = 14989
count = 27
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 347.495880/14979, current 347.664490/14989: elapsed=168635.0us expected=169871.9us +- 849.4us, error 0.7%
(kms_flip:2704) DEBUG: inconsistent vblank ts/seq: last 347.495880/14979, current 347.664490/14989: elapsed=168635.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = flip
last_ts = 347.512726
last_received_ts = 347.512115
last_seq = 14980
current_ts = 347.681366
current_received_ts = 347.680695
current_seq = 14990
count = 28
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 347.512726/14980, current 347.681366/14990: elapsed=168641.0us expected=169871.9us +- 849.4us, error 0.7%
(kms_flip:2704) DEBUG: inconsistent flip ts/seq: last 347.512726/14980, current 347.681366/14990: elapsed=168641.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = vblank
last_ts = 347.664490
last_received_ts = 347.663910
last_seq = 14989
current_ts = 347.833160
current_received_ts = 347.832550
current_seq = 14999
count = 28
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 347.664490/14989, current 347.833160/14999: elapsed=168653.0us expected=169871.9us +- 849.4us, error 0.7%
(kms_flip:2704) DEBUG: inconsistent vblank ts/seq: last 347.664490/14989, current 347.833160/14999: elapsed=168653.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = flip
last_ts = 347.681366
last_received_ts = 347.680695
last_seq = 14990
current_ts = 347.850006
current_received_ts = 347.849396
current_seq = 15000
count = 29
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 347.681366/14990, current 347.850006/15000: elapsed=168625.0us expected=169871.9us +- 849.4us, error 0.7%
(kms_flip:2704) DEBUG: inconsistent flip ts/seq: last 347.681366/14990, current 347.850006/15000: elapsed=168625.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = vblank
last_ts = 347.833160
last_received_ts = 347.832550
last_seq = 14999
current_ts = 348.001617
current_received_ts = 348.001038
current_seq = 15009
count = 29
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 347.833160/14999, current 348.001617/15009: elapsed=168469.0us expected=169871.9us +- 849.4us, error 0.8%
(kms_flip:2704) DEBUG: inconsistent vblank ts/seq: last 347.833160/14999, current 348.001617/15009: elapsed=168469.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = flip
last_ts = 347.850006
last_received_ts = 347.849396
last_seq = 15000
current_ts = 348.018463
current_received_ts = 348.017853
current_seq = 15010
count = 30
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 347.850006/15000, current 348.018463/15010: elapsed=168474.0us expected=169871.9us +- 849.4us, error 0.8%
(kms_flip:2704) DEBUG: inconsistent flip ts/seq: last 347.850006/15000, current 348.018463/15010: elapsed=168474.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = vblank
last_ts = 348.001617
last_received_ts = 348.001038
last_seq = 15009
current_ts = 348.170105
current_received_ts = 348.169464
current_seq = 15019
count = 30
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 348.001617/15009, current 348.170105/15019: elapsed=168475.0us expected=169871.9us +- 849.4us, error 0.8%
(kms_flip:2704) DEBUG: inconsistent vblank ts/seq: last 348.001617/15009, current 348.170105/15019: elapsed=168475.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = flip
last_ts = 348.018463
last_received_ts = 348.017853
last_seq = 15010
current_ts = 348.186951
current_received_ts = 348.186310
current_seq = 15020
count = 31
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 348.018463/15010, current 348.186951/15020: elapsed=168479.0us expected=169871.9us +- 849.4us, error 0.8%
(kms_flip:2704) DEBUG: inconsistent flip ts/seq: last 348.018463/15010, current 348.186951/15020: elapsed=168479.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = vblank
last_ts = 348.170105
last_received_ts = 348.169464
last_seq = 15019
current_ts = 348.338562
current_received_ts = 348.337952
current_seq = 15029
count = 31
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 348.170105/15019, current 348.338562/15029: elapsed=168471.0us expected=169871.9us +- 849.4us, error 0.8%
(kms_flip:2704) DEBUG: inconsistent vblank ts/seq: last 348.170105/15019, current 348.338562/15029: elapsed=168471.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = flip
last_ts = 348.186951
last_received_ts = 348.186310
last_seq = 15020
current_ts = 348.355408
current_received_ts = 348.354828
current_seq = 15030
count = 32
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 348.186951/15020, current 348.355408/15030: elapsed=168469.0us expected=169871.9us +- 849.4us, error 0.8%
(kms_flip:2704) DEBUG: inconsistent flip ts/seq: last 348.186951/15020, current 348.355408/15030: elapsed=168469.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = vblank
last_ts = 348.338562
last_received_ts = 348.337952
last_seq = 15029
current_ts = 348.507050
current_received_ts = 348.506439
current_seq = 15039
count = 32
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 348.338562/15029, current 348.507050/15039: elapsed=168476.0us expected=169871.9us +- 849.4us, error 0.8%
(kms_flip:2704) DEBUG: inconsistent vblank ts/seq: last 348.338562/15029, current 348.507050/15039: elapsed=168476.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = flip
last_ts = 348.355408
last_received_ts = 348.354828
last_seq = 15030
current_ts = 348.523895
current_received_ts = 348.523254
current_seq = 15040
count = 33
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 348.355408/15030, current 348.523895/15040: elapsed=168473.0us expected=169871.9us +- 849.4us, error 0.8%
(kms_flip:2704) DEBUG: inconsistent flip ts/seq: last 348.355408/15030, current 348.523895/15040: elapsed=168473.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = vblank
last_ts = 348.507050
last_received_ts = 348.506439
last_seq = 15039
current_ts = 348.675507
current_received_ts = 348.674957
current_seq = 15049
count = 33
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 348.507050/15039, current 348.675507/15049: elapsed=168470.0us expected=169871.9us +- 849.4us, error 0.8%
(kms_flip:2704) DEBUG: inconsistent vblank ts/seq: last 348.507050/15039, current 348.675507/15049: elapsed=168470.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = flip
last_ts = 348.523895
last_received_ts = 348.523254
last_seq = 15040
current_ts = 348.692352
current_received_ts = 348.691742
current_seq = 15050
count = 34
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 348.523895/15040, current 348.692352/15050: elapsed=168470.0us expected=169871.9us +- 849.4us, error 0.8%
(kms_flip:2704) DEBUG: inconsistent flip ts/seq: last 348.523895/15040, current 348.692352/15050: elapsed=168470.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = vblank
last_ts = 348.675507
last_received_ts = 348.674957
last_seq = 15049
current_ts = 348.843994
current_received_ts = 348.843353
current_seq = 15059
count = 34
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 348.675507/15049, current 348.843994/15059: elapsed=168476.0us expected=169871.9us +- 849.4us, error 0.8%
(kms_flip:2704) DEBUG: inconsistent vblank ts/seq: last 348.675507/15049, current 348.843994/15059: elapsed=168476.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = flip
last_ts = 348.692352
last_received_ts = 348.691742
last_seq = 15050
current_ts = 348.860809
current_received_ts = 348.860168
current_seq = 15060
count = 35
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 348.692352/15050, current 348.860809/15060: elapsed=168453.0us expected=169871.9us +- 849.4us, error 0.8%
(kms_flip:2704) DEBUG: inconsistent flip ts/seq: last 348.692352/15050, current 348.860809/15060: elapsed=168453.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = vblank
last_ts = 348.843994
last_received_ts = 348.843353
last_seq = 15059
current_ts = 349.012299
current_received_ts = 349.011688
current_seq = 15069
count = 35
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 348.843994/15059, current 349.012299/15069: elapsed=168306.0us expected=169871.9us +- 849.4us, error 0.9%
(kms_flip:2704) DEBUG: inconsistent vblank ts/seq: last 348.843994/15059, current 349.012299/15069: elapsed=168306.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = flip
last_ts = 348.860809
last_received_ts = 348.860168
last_seq = 15060
current_ts = 349.029144
current_received_ts = 349.028503
current_seq = 15070
count = 36
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 348.860809/15060, current 349.029144/15070: elapsed=168316.0us expected=169871.9us +- 849.4us, error 0.9%
(kms_flip:2704) DEBUG: inconsistent flip ts/seq: last 348.860809/15060, current 349.029144/15070: elapsed=168316.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = vblank
last_ts = 349.012299
last_received_ts = 349.011688
last_seq = 15069
current_ts = 349.180634
current_received_ts = 349.179993
current_seq = 15079
count = 36
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 349.012299/15069, current 349.180634/15079: elapsed=168332.0us expected=169871.9us +- 849.4us, error 0.9%
(kms_flip:2704) DEBUG: inconsistent vblank ts/seq: last 349.012299/15069, current 349.180634/15079: elapsed=168332.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = flip
last_ts = 349.029144
last_received_ts = 349.028503
last_seq = 15070
current_ts = 349.197449
current_received_ts = 349.196838
current_seq = 15080
count = 37
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 349.029144/15070, current 349.197449/15080: elapsed=168324.0us expected=169871.9us +- 849.4us, error 0.9%
(kms_flip:2704) DEBUG: inconsistent flip ts/seq: last 349.029144/15070, current 349.197449/15080: elapsed=168324.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = vblank
last_ts = 349.180634
last_received_ts = 349.179993
last_seq = 15079
current_ts = 349.348938
current_received_ts = 349.348328
current_seq = 15089
count = 37
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 349.180634/15079, current 349.348938/15089: elapsed=168311.0us expected=169871.9us +- 849.4us, error 0.9%
(kms_flip:2704) DEBUG: inconsistent vblank ts/seq: last 349.180634/15079, current 349.348938/15089: elapsed=168311.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = flip
last_ts = 349.197449
last_received_ts = 349.196838
last_seq = 15080
current_ts = 349.365784
current_received_ts = 349.365173
current_seq = 15090
count = 38
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 349.197449/15080, current 349.365784/15090: elapsed=168334.0us expected=169871.9us +- 849.4us, error 0.9%
(kms_flip:2704) DEBUG: inconsistent flip ts/seq: last 349.197449/15080, current 349.365784/15090: elapsed=168334.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = vblank
last_ts = 349.348938
last_received_ts = 349.348328
last_seq = 15089
current_ts = 349.517273
current_received_ts = 349.516663
current_seq = 15099
count = 38
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 349.348938/15089, current 349.517273/15099: elapsed=168321.0us expected=169871.9us +- 849.4us, error 0.9%
(kms_flip:2704) DEBUG: inconsistent vblank ts/seq: last 349.348938/15089, current 349.517273/15099: elapsed=168321.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = flip
last_ts = 349.365784
last_received_ts = 349.365173
last_seq = 15090
current_ts = 349.534119
current_received_ts = 349.533447
current_seq = 15100
count = 39
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 349.365784/15090, current 349.534119/15100: elapsed=168319.0us expected=169871.9us +- 849.4us, error 0.9%
(kms_flip:2704) DEBUG: inconsistent flip ts/seq: last 349.365784/15090, current 349.534119/15100: elapsed=168319.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = vblank
last_ts = 349.517273
last_received_ts = 349.516663
last_seq = 15099
current_ts = 349.685577
current_received_ts = 349.684998
current_seq = 15109
count = 39
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 349.517273/15099, current 349.685577/15109: elapsed=168319.0us expected=169871.9us +- 849.4us, error 0.9%
(kms_flip:2704) DEBUG: inconsistent vblank ts/seq: last 349.517273/15099, current 349.685577/15109: elapsed=168319.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = flip
last_ts = 349.534119
last_received_ts = 349.533447
last_seq = 15100
current_ts = 349.702423
current_received_ts = 349.701752
current_seq = 15110
count = 40
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 349.534119/15100, current 349.702423/15110: elapsed=168309.0us expected=169871.9us +- 849.4us, error 0.9%
(kms_flip:2704) DEBUG: inconsistent flip ts/seq: last 349.534119/15100, current 349.702423/15110: elapsed=168309.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = vblank
last_ts = 349.685577
last_received_ts = 349.684998
last_seq = 15109
current_ts = 349.853912
current_received_ts = 349.853241
current_seq = 15119
count = 40
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 349.685577/15109, current 349.853912/15119: elapsed=168326.0us expected=169871.9us +- 849.4us, error 0.9%
(kms_flip:2704) DEBUG: inconsistent vblank ts/seq: last 349.685577/15109, current 349.853912/15119: elapsed=168326.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = flip
last_ts = 349.702423
last_received_ts = 349.701752
last_seq = 15110
current_ts = 349.870697
current_received_ts = 349.870087
current_seq = 15120
count = 41
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 349.702423/15110, current 349.870697/15120: elapsed=168291.0us expected=169871.9us +- 849.4us, error 0.9%
(kms_flip:2704) DEBUG: inconsistent flip ts/seq: last 349.702423/15110, current 349.870697/15120: elapsed=168291.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = vblank
last_ts = 349.853912
last_received_ts = 349.853241
last_seq = 15119
current_ts = 350.022064
current_received_ts = 350.021454
current_seq = 15129
count = 41
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 349.853912/15119, current 350.022064/15129: elapsed=168168.0us expected=169871.9us +- 849.4us, error 1.0%
(kms_flip:2704) DEBUG: inconsistent vblank ts/seq: last 349.853912/15119, current 350.022064/15129: elapsed=168168.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = flip
last_ts = 349.870697
last_received_ts = 349.870087
last_seq = 15120
current_ts = 350.038879
current_received_ts = 350.038269
current_seq = 15130
count = 42
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 349.870697/15120, current 350.038879/15130: elapsed=168184.0us expected=169871.9us +- 849.4us, error 1.0%
(kms_flip:2704) DEBUG: inconsistent flip ts/seq: last 349.870697/15120, current 350.038879/15130: elapsed=168184.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = vblank
last_ts = 350.022064
last_received_ts = 350.021454
last_seq = 15129
current_ts = 350.190277
current_received_ts = 350.189636
current_seq = 15139
count = 42
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 350.022064/15129, current 350.190277/15139: elapsed=168196.0us expected=169871.9us +- 849.4us, error 1.0%
(kms_flip:2704) DEBUG: inconsistent vblank ts/seq: last 350.022064/15129, current 350.190277/15139: elapsed=168196.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = flip
last_ts = 350.038879
last_received_ts = 350.038269
last_seq = 15130
current_ts = 350.207092
current_received_ts = 350.206421
current_seq = 15140
count = 43
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 350.038879/15130, current 350.207092/15140: elapsed=168192.0us expected=169871.9us +- 849.4us, error 1.0%
(kms_flip:2704) DEBUG: inconsistent flip ts/seq: last 350.038879/15130, current 350.207092/15140: elapsed=168192.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = vblank
last_ts = 350.190277
last_received_ts = 350.189636
last_seq = 15139
current_ts = 350.358459
current_received_ts = 350.357819
current_seq = 15149
count = 43
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 350.190277/15139, current 350.358459/15149: elapsed=168179.0us expected=169871.9us +- 849.4us, error 1.0%
(kms_flip:2704) DEBUG: inconsistent vblank ts/seq: last 350.190277/15139, current 350.358459/15149: elapsed=168179.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = flip
last_ts = 350.207092
last_received_ts = 350.206421
last_seq = 15140
current_ts = 350.375275
current_received_ts = 350.374664
current_seq = 15150
count = 44
seq_step = 1
(kms_flip:2704) DEBUG: flip ts/seq: last 350.207092/15140, current 350.375275/15150: elapsed=168196.0us expected=169871.9us +- 849.4us, error 1.0%
(kms_flip:2704) DEBUG: inconsistent flip ts/seq: last 350.207092/15140, current 350.375275/15150: elapsed=168196.0us expected=169871.9us
(kms_flip:2704) DEBUG: name = vblank
last_ts = 350.358459
last_received_ts = 350.357819
last_seq = 15149
current_ts = 350.526642
current_received_ts = 350.525970
current_seq = 15159
count = 44
seq_step = 10
(kms_flip:2704) DEBUG: vblank ts/seq: last 350.358459/15149, current 350.526642/15159: elapsed=168186.0us expected=169871.9us +- 849.4us, error 1.0%
(kms_flip:2704) DEBUG: inconsistent vblank ts/seq: last 350.358459/15149, current 350.526642/15159: elapsed=168186.0us expected=169871.9us
(kms_flip:2704) INFO: Event flip: expected 447, counted 450, passrate = 40.00%, encoder type 1
(kms_flip:2704) DEBUG: dropped frames, expected 447, counted 450, passrate = 40.00%, encoder type 1
(kms_flip:2704) INFO: Event vblank: expected 447, counted 450, passrate = 37.78%, encoder type 1
(kms_flip:2704) DEBUG: dropped frames, expected 447, counted 450, passrate = 37.78%, encoder type 1
(kms_flip:2704) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1707:
(kms_flip:2704) CRITICAL: Failed assertion: !retried
(kms_flip:2704) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:2704) igt_core-INFO: Stack trace:
(kms_flip:2704) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2704) igt_core-INFO:   #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
(kms_flip:2704) igt_core-INFO:   #2 ../tests/kms_flip.c:1887 run_test()
(kms_flip:2704) igt_core-INFO:   #3 ../tests/kms_flip.c:2156 __igt_unique____real_main2043()
(kms_flip:2704) igt_core-INFO:   #4 ../tests/kms_flip.c:2043 main()
(kms_flip:2704) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2704) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2704) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-VGA1: FAIL (19.065s)
Dmesg
<6> [330.916593] Console: switching to colour dummy device 80x25
<6> [330.917595] [IGT] kms_flip: executing
<7> [330.923815] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [330.924981] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [330.925589] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [330.938312] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:72:VGA-1]
<7> [330.938326] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:72:VGA-1] force=1
<7> [330.938576] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] ironlake hotplug adpa=0xf40000, result 0
<7> [330.938791] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT not detected via hotplug
<7> [330.939214] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [330.939438] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7> [330.939804] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [330.940058] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [330.940064] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7> [330.940308] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7> [330.941526] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [330.941531] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7> [330.941740] i915 0000:00:02.0: [drm:intel_crt_detect_ddc [i915]] CRT not detected via DDC:0x50 [no valid EDID found]
<7> [330.941984] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:72:VGA-1] disconnected
<7> [330.942454] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:83:DP-1]
<7> [330.942462] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:83:DP-1]
<7> [330.945239] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [330.948108] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [330.951000] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [330.953890] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [330.956783] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [330.959672] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [330.962546] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [330.965442] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [330.968352] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [330.971240] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [330.974120] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [330.977005] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [330.979935] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [330.982831] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [330.985739] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [330.988655] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [330.991567] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [330.994475] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [330.997422] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.000352] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.003315] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.006205] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.009107] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.012028] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.014997] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.017928] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.020931] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.023956] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.026942] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.029892] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.032842] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.035857] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.036265] i915 0000:00:02.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX D/DP D: Too many retries, giving up. First error: -110
<7> [331.038905] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.041867] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.044883] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.047798] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.050723] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.053760] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.056726] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.059724] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.062718] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.065729] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.068760] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.071736] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.074702] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.077644] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.080577] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.083515] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.086499] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.089495] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.092412] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.095397] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.098398] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.101285] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.104301] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.107277] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.110221] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.113149] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.116089] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.118975] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.121951] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.124939] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.127977] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.130986] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.131390] i915 0000:00:02.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX D/DP D: Too many retries, giving up. First error: -110
<7> [331.131447] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:83:DP-1] disconnected
<7> [331.131956] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:72:VGA-1]
<7> [331.131965] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:72:VGA-1] force=1
<7> [331.132307] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] ironlake hotplug adpa=0xf40000, result 0
<7> [331.132638] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT not detected via hotplug
<7> [331.133067] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [331.133310] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7> [331.133709] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [331.134072] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [331.134079] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7> [331.134313] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7> [331.135531] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [331.135537] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7> [331.135751] i915 0000:00:02.0: [drm:intel_crt_detect_ddc [i915]] CRT not detected via DDC:0x50 [no valid EDID found]
<7> [331.135979] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:72:VGA-1] disconnected
<7> [331.136279] i915 0000:00:02.0: [drm:status_store] [CONNECTOR:72:VGA-1] force updated from 0 to 2 or reprobing
<7> [331.136287] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:72:VGA-1]
<7> [331.136293] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:72:VGA-1] status updated from disconnected to connected
<7> [331.136446] i915 0000:00:02.0: [drm:drm_sysfs_hotplug_event] generating hotplug event
<7> [331.136530] i915 0000:00:02.0: [drm:drm_client_hotplug] fbdev: ret=0
<7> [331.136496] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [331.136725] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7> [331.137170] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [331.137403] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [331.137419] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7> [331.137641] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7> [331.138934] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [331.138948] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7> [331.139239] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:72:VGA-1] probed modes:
<7> [331.139246] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [331.139252] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5
<7> [331.139257] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 56 36000 800 824 896 1024 600 601 603 625 0x40 0x5
<7> [331.139262] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "848x480": 60 33750 848 864 976 1088 480 486 494 517 0x40 0x5
<7> [331.139267] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7> [331.139330] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:72:VGA-1]
<7> [331.139557] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [331.139787] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7> [331.140188] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [331.140408] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [331.140421] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7> [331.140634] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7> [331.141897] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [331.141909] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7> [331.142203] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:72:VGA-1] probed modes:
<7> [331.142209] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [331.142214] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5
<7> [331.142220] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 56 36000 800 824 896 1024 600 601 603 625 0x40 0x5
<7> [331.142225] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "848x480": 60 33750 848 864 976 1088 480 486 494 517 0x40 0x5
<7> [331.142230] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7> [331.142257] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:75:HDMI-A-1]
<7> [331.142266] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:75:HDMI-A-1]
<7> [331.167430] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:75:HDMI-A-1] HDMI: DVI dual 0, max TMDS clock 590000 kHz
<7> [331.167441] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:75:HDMI-A-1] HDMI sink does deep color 30.
<7> [331.167446] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:75:HDMI-A-1] HDMI sink does deep color 36.
<7> [331.167449] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:75:HDMI-A-1] HDMI sink does deep color 48.
<7> [331.167453] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:75:HDMI-A-1] Assigning HDMI sink color depth as 16 bpc.
<7> [331.167456] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:75:HDMI-A-1] HDMI sink does YCRCB444 in deep color.
<7> [331.167461] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:75:HDMI-A-1] ELD monitor Mi TV
<7> [331.167466] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:75:HDMI-A-1] HDMI: latency present 0 0, video latency 0 0, audio latency 0 0
<7> [331.167470] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:75:HDMI-A-1] ELD size 36, SAD count 3
<7> [331.167666] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK for addr: 0040 w(1)
<7> [331.167961] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK on first message, retry
<7> [331.168396] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK for addr: 0040 w(1)
<7> [331.168609] i915 0000:00:02.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [331.169762] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "2560x1600": 60 268500 2560 2608 2640 2720 1600 1603 1609 1646 0x40 0x5 (CLOCK_HIGH)
<7> [331.169775] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "2560x1440": 60 241500 2560 2608 2640 2720 1440 1443 1449 1481 0x40 0x5 (CLOCK_HIGH)
<7> [331.169784] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 30 297000 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [331.169792] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "4096x2160": 30 297000 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [331.169800] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080": 120 297000 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5 (CLOCK_HIGH)
<7> [331.169807] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 25 297000 3840 4896 4984 5280 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [331.169815] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 24 297000 3840 5116 5204 5500 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [331.169823] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "4096x2160": 24 297000 4096 5116 5204 5500 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [331.169830] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 50 594000 3840 4896 4984 5280 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [331.169838] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 60 594000 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [331.169846] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "4096x2160": 50 594000 4096 5064 5152 5280 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [331.169853] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "4096x2160": 60 594000 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [331.169861] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 30 296703 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [331.169868] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "4096x2160": 30 296703 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [331.169893] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080": 120 296703 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5 (CLOCK_HIGH)
<7> [331.169902] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 24 296703 3840 5116 5204 5500 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [331.169911] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "4096x2160": 24 296703 4096 5116 5204 5500 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [331.169918] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 60 593407 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [331.169926] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "4096x2160": 60 593407 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [331.169939] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:75:HDMI-A-1] probed modes:
<7> [331.169948] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [331.169957] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2880x1800": 30 167000 2880 2910 2920 3030 1800 1820 1828 1836 0x40 0x5
<7> [331.169965] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1200": 60 154000 1920 1968 2000 2080 1200 1203 1209 1235 0x40 0x9
<7> [331.169981] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [331.169989] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 60 148352 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [331.169998] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080i": 60 74250 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x15
<7> [331.170006] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080i": 60 74250 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x20015
<7> [331.170015] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080i": 60 74176 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x15
<7> [331.170023] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080i": 60 74176 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x20015
<7> [331.170032] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 50 148500 1920 2448 2492 2640 1080 1084 1089 1125 0x40 0x5
<7> [331.170040] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080i": 50 74250 1920 2448 2492 2640 1080 1084 1094 1125 0x40 0x15
<7> [331.170049] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080i": 50 74250 1920 2448 2492 2640 1080 1084 1094 1125 0x40 0x20015
<7> [331.170058] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 30 74250 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [331.170066] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 30 74176 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [331.170074] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 25 74250 1920 2448 2492 2640 1080 1084 1089 1125 0x40 0x5
<7> [331.170082] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 24 74250 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x5
<7> [331.170091] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 24 74250 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x1c005
<7> [331.170099] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 24 74250 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x4005
<7> [331.170108] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 24 74176 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x5
<7> [331.170116] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 24 74176 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x1c005
<7> [331.170124] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 24 74176 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x4005
<7> [331.170133] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x1200": 60 162000 1600 1664 1856 2160 1200 1201 1204 1250 0x40 0x5
<7> [331.170141] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1680x1050": 60 119000 1680 1728 1760 1840 1050 1053 1059 1080 0x40 0x9
<7> [331.170150] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x900": 60 108000 1600 1624 1704 1800 900 901 904 1000 0x40 0x5
<7> [331.170158] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x1024": 60 108000 1280 1328 1440 1688 1024 1025 1028 1066 0x40 0x5
<7> [331.170167] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1440x900": 60 88750 1440 1488 1520 1600 900 903 909 926 0x40 0x9
<7> [331.170175] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1366x768": 60 85500 1366 1436 1579 1792 768 771 774 798 0x40 0x5
<7> [331.170184] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x800": 60 71000 1280 1328 1360 1440 800 803 809 823 0x40 0x9
<7> [331.170192] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74250 1280 1390 1430 1650 720 725 730 750 0x40 0x5
<7> [331.170201] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74250 1280 1390 1430 1650 720 725 730 750 0x40 0x1c005
<7> [331.170210] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74250 1280 1390 1430 1650 720 725 730 750 0x40 0x4005
<7> [331.170218] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74176 1280 1390 1430 1650 720 725 730 750 0x40 0x5
<7> [331.170227] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74176 1280 1390 1430 1650 720 725 730 750 0x40 0x1c005
<7> [331.170236] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74176 1280 1390 1430 1650 720 725 730 750 0x40 0x4005
<7> [331.170244] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 50 74250 1280 1720 1760 1980 720 725 730 750 0x40 0x5
<7> [331.170252] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 50 74250 1280 1720 1760 1980 720 725 730 750 0x40 0x1c005
<7> [331.170261] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 50 74250 1280 1720 1760 1980 720 725 730 750 0x40 0x4005
<7> [331.170270] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [331.170278] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5
<7> [331.170287] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "720x576": 50 27000 720 732 796 864 576 581 586 625 0x40 0xa
<7> [331.170295] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "720x576i": 50 13500 720 732 795 864 576 580 586 625 0x40 0x101a
<7> [331.170304] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "720x480": 60 27027 720 736 798 858 480 489 495 525 0x40 0xa
<7> [331.170312] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "720x480": 60 27000 720 736 798 858 480 489 495 525 0x40 0xa
<7> [331.170321] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25200 640 656 752 800 480 490 492 525 0x40 0xa
<7> [331.170330] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7> [331.170407] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:83:DP-1]
<7> [331.170418] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:83:DP-1]
<7> [331.173213] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.176082] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.178973] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.181869] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.184776] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.187694] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.190594] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.193470] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.196324] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.199180] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.202056] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.204936] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.207804] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.210706] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.213559] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.216436] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.219280] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.222166] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.225044] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.227932] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.230814] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.233702] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.236591] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.239468] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.242334] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.245216] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.248150] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.251066] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.253971] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.256893] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.259848] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.262849] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.263287] i915 0000:00:02.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX D/DP D: Too many retries, giving up. First error: -110
<7> [331.265933] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.268845] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.271835] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.274846] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.277856] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.280891] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.283870] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.286900] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.289893] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.292807] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.295799] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.298770] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.301785] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.304797] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.307831] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.310789] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.313779] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.316813] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.319749] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.322709] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.325716] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.328736] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.331767] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.334759] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.337781] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.340771] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.343846] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.346867] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.349846] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.352777] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.355780] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.358735] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [331.359093] i915 0000:00:02.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX D/DP D: Too many retries, giving up. First error: -110
<7> [331.359135] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:83:DP-1] disconnected
<6> [331.368515] [IGT] kms_flip: starting subtest flip-vs-absolute-wf_vblank-interruptible
<6> [331.370222] [IGT] kms_flip: starting dynamic subtest A-VGA1
<7> [331.370783] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:90]
<7> [331.370939] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:92]
<7> [331.450207] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:52:pipe A]
<7> [331.450380] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [331.450678] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [331.450973] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [331.451233] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in has_pch_encoder (expected yes, found no)
<7> [331.451472] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in fdi_lanes (expected 3, found 0)
<7> [331.451698] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in fdi_m_n (expected tu 64 data 6934582/8388608 link 288940/524288, found tu 0, data 0/0 link 0/0)
<7> [331.451975] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [331.452348] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [331.452721] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [331.453124] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [331.453495] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [331.453948] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [331.454331] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [331.455070] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [331.455782] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [331.456628] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [331.457351] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [331.458005] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [331.458361] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [331.458716] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [331.459122] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [331.459468] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [331.459813] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [331.460192] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [331.460537] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [331.460922] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [331.461268] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [331.461614] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [331.461987] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [331.462333] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [331.462677] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [331.463059] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [331.463405] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [331.463749] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [331.464139] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [331.464484] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [331.464840] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [331.465215] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [331.465577] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [331.465962] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148800, found 0)
<7> [331.466308] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148800, found 0)
<7> [331.466654] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in port_clock (expected 223200, found 0)
<7> [331.467026] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [331.467388] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [331.467733] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in avi infoframe
<7> [331.468102] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [331.468446] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [331.468451] i915 0000:00:02.0: colorspace: RGB
<7> [331.468456] i915 0000:00:02.0: scan mode: Underscan
<7> [331.468459] i915 0000:00:02.0: colorimetry: No Data
<7> [331.468463] i915 0000:00:02.0: picture aspect: 16:9
<7> [331.468467] i915 0000:00:02.0: active aspect: Same as Picture
<7> [331.468471] i915 0000:00:02.0: itc: No Data
<7> [331.468475] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [331.468479] i915 0000:00:02.0: quantization range: Default
<7> [331.468483] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [331.468486] i915 0000:00:02.0: video code: 16
<7> [331.468490] i915 0000:00:02.0: ycc quantization range: Full
<7> [331.468494] i915 0000:00:02.0: hdmi content type: Graphics
<7> [331.468498] i915 0000:00:02.0: pixel repeat: 0
<7> [331.468502] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [331.468506] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [331.468850] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in spd infoframe
<7> [331.469240] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [331.469600] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [331.469605] i915 0000:00:02.0: vendor: Intel
<7> [331.469609] i915 0000:00:02.0: product: Integrated gfx
<7> [331.469613] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [331.469618] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [331.469989] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hdmi infoframe
<7> [331.470350] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [331.470709] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [331.470714] i915 0000:00:02.0: empty frame
<7> [331.470718] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [331.471098] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:52:pipe A] fastset requirement not met, forcing full modeset
<7> [331.471445] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:52:pipe A] releasing PCH DPLL A
<7> [331.471829] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:52:pipe A] min cdclk: 165334 kHz -> 0 kHz
<7> [331.472257] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:52:pipe A] enable: no [modeset]
<7> [331.472618] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:primary A] fb: [NOFB], visible: no
<7> [331.473048] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:75:HDMI-A-1][ENCODER:74:HDMI D] Disable audio codec on [CRTC:52:pipe A]
<7> [331.491183] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [331.510180] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found
<7> [331.510657] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found
<7> [331.511170] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found
<7> [331.520249] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PCH DPLL A (active 0x1, on? 1) for [CRTC:52:pipe A]
<7> [331.520786] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PCH DPLL A
<7> [331.521647] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:73:CRT]
<7> [331.522381] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:HDMI D]
<7> [331.523133] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:82:DP D]
<7> [331.523838] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:75:HDMI-A-1]
<7> [331.524494] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:52:pipe A]
<7> [331.524849] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:71:pipe B]
<7> [331.525029] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:52:pipe A]
<7> [331.525053] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:72:VGA-1]
<7> [331.525142] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:72:VGA-1] Limiting target display pipe bpp to 24 (EDID bpp 0, max requested bpp 0, max platform bpp 36)
<7> [331.525384] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:52:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [331.525621] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe A, lanes 1
<7> [331.525964] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [331.526319] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [331.526672] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [331.527045] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in has_pch_encoder (expected no, found yes)
<7> [331.527348] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in fdi_lanes (expected 0, found 1)
<7> [331.527583] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in fdi_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6039797/8388608 link 125829/524288)
<7> [331.527818] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000002)
<7> [331.528078] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [331.528312] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1024)
<7> [331.528544] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 1344)
<7> [331.528775] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1024)
<7> [331.529135] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 1344)
<7> [331.529480] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1048)
<7> [331.529824] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 1184)
<7> [331.530210] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 768)
<7> [331.530485] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 768)
<7> [331.530808] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 771)
<7> [331.531095] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 777)
<7> [331.531330] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 806)
<7> [331.531556] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 806)
<7> [331.531792] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1024)
<7> [331.532068] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 1344)
<7> [331.532310] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1024)
<7> [331.532552] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 1344)
<7> [331.532778] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1048)
<7> [331.533059] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 1184)
<7> [331.533295] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 768)
<7> [331.533526] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 768)
<7> [331.533793] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 771)
<7> [331.534046] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 777)
<7> [331.534290] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 806)
<7> [331.534516] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 806)
<7> [331.534741] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [331.535013] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [331.535265] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [331.535492] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in dpll_hw_state
<7> [331.535717] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [331.535979] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0xc4010001, dpll_md: 0x0, fp0: 0x30f06, fp1: 0x30f06
<7> [331.536215] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [331.536440] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [331.536664] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [331.536939] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 64800)
<7> [331.537205] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 64800)
<7> [331.537430] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in port_clock (expected 0, found 64800)
<7> [331.537657] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:52:pipe A] fastset requirement not met, forcing full modeset
<7> [331.537943] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:52:pipe A] min cdclk: 0 kHz -> 72000 kHz
<7> [331.538191] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:52:pipe A] allocated PCH DPLL A
<7> [331.538413] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:52:pipe A] reserving PCH DPLL A
<7> [331.538647] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:52:pipe A] enable: yes [modeset]
<7> [331.538927] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB
<7> [331.539270] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [331.539612] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [331.539990] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [331.540348] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [331.540691] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [331.541080] 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> [331.541424] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [331.541768] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1
<7> [331.542171] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [331.542523] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0
<7> [331.542875] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 0, vmax vblank: 0, vmin vtotal: 0, vmax vtotal: 0
<7> [331.543265] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [331.543265] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [331.543625] 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> [331.543989] 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> [331.544318] 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> [331.544664] 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> [331.545045] 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> [331.545392] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 64800, pipe src: 1024x768+0+0, pixel rate 64800, min cdclk 72000
<7> [331.545735] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0
<7> [331.546120] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [331.546480] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [331.546824] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [331.547202] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [331.547546] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [331.547917] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [331.548284] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [331.548626] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [331.548990] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [331.549290] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [331.549634] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:primary A] fb: [FB:90] 1024x768 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [331.550018] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [331.550361] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0
<7> [331.551684] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [331.552086] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:73:CRT]
<7> [331.552421] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:HDMI D]
<7> [331.552756] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:82:DP D]
<7> [331.553735] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [331.554961] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100
<7> [331.555312] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done.
<7> [331.556315] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x200
<7> [331.556656] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done.
<7> [331.557029] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train done.
<7> [331.557372] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PCH DPLL A (active 0x1, on? 0) for [CRTC:52:pipe A]
<7> [331.557716] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PCH DPLL A
<7> [331.609879] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:72:VGA-1]
<7> [331.610473] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:52:pipe A]
<7> [342.383792] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:52:pipe A]
<7> [342.384003] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [342.384617] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [342.385201] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [342.385723] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in has_pch_encoder (expected yes, found no)
<7> [342.386275] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in fdi_lanes (expected 1, found 0)
<7> [342.386716] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in fdi_m_n (expected tu 64 data 6039797/8388608 link 125829/524288, found tu 0, data 0/0 link 0/0)
<7> [342.387254] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in output_types (expected 0x00000002, found 0x00000000)
<7> [342.387723] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [342.388233] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1024, found 0)
<7> [342.388515] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 1344, found 0)
<7> [342.388747] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1024, found 0)
<7> [342.388980] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 1344, found 0)
<7> [342.389221] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1048, found 0)
<7> [342.389454] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 1184, found 0)
<7> [342.389686] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 768, found 0)
<7> [342.389918] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 768, found 0)
<7> [342.390162] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 771, found 0)
<7> [342.390394] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 777, found 0)
<7> [342.390627] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 806, found 0)
<7> [342.390858] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 806, found 0)
<7> [342.391146] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1024, found 0)
<7> [342.391379] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 1344, found 0)
<7> [342.391611] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1024, found 0)
<7> [342.391843] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 1344, found 0)
<7> [342.392093] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1048, found 0)
<7> [342.392325] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 1184, found 0)
<7> [342.392557] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 768, found 0)
<7> [342.392789] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 768, found 0)
<7> [342.393040] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 771, found 0)
<7> [342.393273] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 777, found 0)
<7> [342.393505] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 806, found 0)
<7> [342.393737] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 806, found 0)
<7> [342.393989] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [342.394221] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [342.394454] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [342.394686] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [342.394918] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 64800, found 0)
<7> [342.395166] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 64800, found 0)
<7> [342.395398] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in port_clock (expected 64800, found 0)
<7> [342.395631] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:52:pipe A] fastset requirement not met, forcing full modeset
<7> [342.395868] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:52:pipe A] releasing PCH DPLL A
<7> [342.396164] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:52:pipe A] min cdclk: 72000 kHz -> 0 kHz
<7> [342.396436] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:52:pipe A] enable: no [modeset]
<7> [342.396663] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:primary A] fb: [NOFB], visible: no
<7> [342.397107] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [342.410530] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found
<7> [342.411078] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found
<7> [342.411567] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found
<7> [342.428337] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PCH DPLL A (active 0x1, on? 1) for [CRTC:52:pipe A]
<7> [342.428879] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PCH DPLL A
<7> [342.429650] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:73:CRT]
<7> [342.429985] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:HDMI D]
<7> [342.430341] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:82:DP D]
<7> [342.430683] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:72:VGA-1]
<7> [342.431071] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:52:pipe A]
<7> [342.431638] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:71:pipe B]
<7> [342.431787] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:52:pipe A]
<7> [342.431810] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:72:VGA-1]
<7> [342.431894] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:72:VGA-1] Limiting target display pipe bpp to 24 (EDID bpp 0, max requested bpp 0, max platform bpp 36)
<7> [342.432129] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:52:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [342.432387] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe A, lanes 1
<7> [342.432628] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [342.432862] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [342.433095] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [342.433340] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in has_pch_encoder (expected no, found yes)
<7> [342.433606] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in fdi_lanes (expected 0, found 1)
<7> [342.433839] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in fdi_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6039797/8388608 link 125829/524288)
<7> [342.434073] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000002)
<7> [342.434324] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [342.434557] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1024)
<7> [342.434789] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 1344)
<7> [342.435020] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1024)
<7> [342.435252] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 1344)
<7> [342.435520] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1048)
<7> [342.435759] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 1184)
<7> [342.435991] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 768)
<7> [342.436223] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 768)
<7> [342.436475] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 771)
<7> [342.436708] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 777)
<7> [342.436940] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 806)
<7> [342.437171] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 806)
<7> [342.437443] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1024)
<7> [342.437677] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 1344)
<7> [342.437914] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1024)
<7> [342.438139] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 1344)
<7> [342.438411] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1048)
<7> [342.438656] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 1184)
<7> [342.438890] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 768)
<7> [342.439115] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 768)
<7> [342.439377] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 771)
<7> [342.439619] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 777)
<7> [342.439845] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 806)
<7> [342.440070] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 806)
<7> [342.440325] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [342.440570] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [342.440795] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [342.441021] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [342.441299] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 64800)
<7> [342.441533] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 64800)
<7> [342.441759] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:52:pipe A] fastset requirement not met in port_clock (expected 0, found 64800)
<7> [342.441985] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:52:pipe A] fastset requirement not met, forcing full modeset
<7> [342.442278] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:52:pipe A] min cdclk: 0 kHz -> 72000 kHz
<7> [342.442522] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:52:pipe A] allocated PCH DPLL A
<7> [342.442744] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:52:pipe A] reserving PCH DPLL A
<7> [342.442970] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:52:pipe A] enable: yes [modeset]
<7> [342.443237] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB
<7> [342.443472] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [342.443696] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [342.443932] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [342.444198] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [342.444438] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [342.444662] 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> [342.444886] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [342.445147] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1
<7> [342.445393] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [342.445617] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0
<7> [342.445841] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 0, vmax vblank: 0, vmin vtotal: 0, vmax vtotal: 0
<7> [342.446077] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [342.446343] 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> [342.446579] 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> [342.446803] 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> [342.447029] 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> [342.447300] 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> [342.447535] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 64800, pipe src: 1024x768+0+0, pixel rate 64800, min cdclk 72000
<7> [342.447760] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0
<7> [342.447983] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [342.448246] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [342.448480] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [342.448704] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [342.448937] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [342.449079] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [342.449277] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [342.449582] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [342.449806] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [342.450081] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [342.450335] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [342.450559] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:primary A] fb: [FB:90] 1024x768 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [342.450786] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [342.451042] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0
<7> [342.451329] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [342.451562] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:73:CRT]
<7> [342.451779] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:HDMI D]
<7> [342.452032] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:82:DP D]
<7> [342.452848] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [342.453961] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100
<7> [342.454196] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done.
<7> [342.455076] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x200
<7> [342.455298] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done.
<7> [342.455517] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train done.
<7> [342.455740] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PCH DPLL A (active 0x1, on? 0) for [CRTC:52:pipe A]
<7> [342.456010] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PCH DPLL A
<7> [342.508004] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:72:VGA-1]
<7> [342.508605] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:52:pipe A]
<6> [350.438520] [IGT] kms_flip: finished subtest A-VGA1, FAIL
Created at 2026-02-23 22:34:28