Results for igt@kms_flip@plain-flip-ts-check-interruptible@a-hdmi-a1

Result: Fail

integration-manifest git-log-oneline i915_display_info0 igt_runner0 runtimes0 results0.json results0-i915-load.json guc_logs0.tar i915_display_info_post_exec0 boot0 dmesg0

DetailValue
Duration 18.73 seconds
Hostname 
shard-tglu-1
Igt-Version 
IGT-Version: 2.3-ge61a8b4ea (x86_64) (Linux: 6.19.0-rc5-CI_DRM_17808-g630dc29de423+ x86_64)
Out 
Using IGT_SRANDOM=1768246411 for randomisation
Opened device: /dev/dri/card0
Using monotonic timestamps
Starting subtest: plain-flip-ts-check-interruptible
Starting dynamic subtest: A-HDMI-A1
  1920x1080: 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5 
Expected frametime: 18974us; measured 19800.4us +- 6.663us accuracy 0.10%
vblank interval differs from modeline! expected 18973.8us, measured 19800us +- 6.663us, difference 826.7us (124.1 sigma)
Event flip: expected 378, counted 385, passrate = 7.79%, encoder type 2
Expected frametime: 18974us; measured 19172.1us +- 9.605us accuracy 0.15%
vblank interval differs from modeline! expected 18973.8us, measured 19172us +- 9.605us, difference 198.4us (20.7 sigma)
Event flip: expected 391, counted 393, passrate = 68.19%, encoder type 2
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:1889 run_test()
  #3 ../tests/kms_flip.c:2158 __igt_unique____real_main2045()
  #4 ../tests/kms_flip.c:2045 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest A-HDMI-A1: FAIL (18.729s)
Err 
Starting subtest: plain-flip-ts-check-interruptible
Starting dynamic subtest: A-HDMI-A1
(kms_flip:2394) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1709:
(kms_flip:2394) CRITICAL: Failed assertion: !retried
(kms_flip:2394) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest A-HDMI-A1 failed.
**** DEBUG ****
(kms_flip:2394) DEBUG: flip ts/seq: last 130.504059/3705, current 130.523117/3706: elapsed=19064.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 130.504059/3705, current 130.523117/3706: elapsed=19064.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 130.523117
last_received_ts = 130.522568
last_seq = 3706
current_ts = 130.542191
current_received_ts = 130.541534
current_seq = 3707
count = 313
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 130.523117/3706, current 130.542191/3707: elapsed=19075.0us expected=19172.1us +- 95.9us, error 0.5%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 130.523117/3706, current 130.542191/3707: elapsed=19075.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 130.542191
last_received_ts = 130.541534
last_seq = 3707
current_ts = 130.561264
current_received_ts = 130.560608
current_seq = 3708
count = 314
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 130.542191/3707, current 130.561264/3708: elapsed=19069.0us expected=19172.1us +- 95.9us, error 0.5%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 130.542191/3707, current 130.561264/3708: elapsed=19069.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 130.561264
last_received_ts = 130.560608
last_seq = 3708
current_ts = 130.580338
current_received_ts = 130.579681
current_seq = 3709
count = 315
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 130.561264/3708, current 130.580338/3709: elapsed=19066.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 130.561264/3708, current 130.580338/3709: elapsed=19066.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 130.580338
last_received_ts = 130.579681
last_seq = 3709
current_ts = 130.599396
current_received_ts = 130.598724
current_seq = 3710
count = 316
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 130.580338/3709, current 130.599396/3710: elapsed=19062.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 130.580338/3709, current 130.599396/3710: elapsed=19062.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 130.599396
last_received_ts = 130.598724
last_seq = 3710
current_ts = 130.618469
current_received_ts = 130.617798
current_seq = 3711
count = 317
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 130.599396/3710, current 130.618469/3711: elapsed=19072.0us expected=19172.1us +- 95.9us, error 0.5%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 130.599396/3710, current 130.618469/3711: elapsed=19072.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 130.618469
last_received_ts = 130.617798
last_seq = 3711
current_ts = 130.637527
current_received_ts = 130.636871
current_seq = 3712
count = 318
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 130.618469/3711, current 130.637527/3712: elapsed=19065.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 130.618469/3711, current 130.637527/3712: elapsed=19065.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 130.637527
last_received_ts = 130.636871
last_seq = 3712
current_ts = 130.656601
current_received_ts = 130.655945
current_seq = 3713
count = 319
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 130.637527/3712, current 130.656601/3713: elapsed=19074.0us expected=19172.1us +- 95.9us, error 0.5%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 130.637527/3712, current 130.656601/3713: elapsed=19074.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 130.656601
last_received_ts = 130.655945
last_seq = 3713
current_ts = 130.675674
current_received_ts = 130.675003
current_seq = 3714
count = 320
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 130.656601/3713, current 130.675674/3714: elapsed=19064.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 130.656601/3713, current 130.675674/3714: elapsed=19064.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 130.675674
last_received_ts = 130.675003
last_seq = 3714
current_ts = 130.694733
current_received_ts = 130.694077
current_seq = 3715
count = 321
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 130.675674/3714, current 130.694733/3715: elapsed=19061.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 130.675674/3714, current 130.694733/3715: elapsed=19061.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 130.694733
last_received_ts = 130.694077
last_seq = 3715
current_ts = 130.713806
current_received_ts = 130.713165
current_seq = 3716
count = 322
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 130.694733/3715, current 130.713806/3716: elapsed=19076.0us expected=19172.1us +- 95.9us, error 0.5%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 130.694733/3715, current 130.713806/3716: elapsed=19076.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 130.713806
last_received_ts = 130.713165
last_seq = 3716
current_ts = 130.732864
current_received_ts = 130.732193
current_seq = 3717
count = 323
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 130.713806/3716, current 130.732864/3717: elapsed=19055.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 130.713806/3716, current 130.732864/3717: elapsed=19055.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 130.732864
last_received_ts = 130.732193
last_seq = 3717
current_ts = 130.751938
current_received_ts = 130.751312
current_seq = 3718
count = 324
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 130.732864/3717, current 130.751938/3718: elapsed=19079.0us expected=19172.1us +- 95.9us, error 0.5%
(kms_flip:2394) DEBUG: name = flip
last_ts = 130.751938
last_received_ts = 130.751312
last_seq = 3718
current_ts = 130.770996
current_received_ts = 130.770508
current_seq = 3719
count = 325
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 130.751938/3718, current 130.770996/3719: elapsed=19064.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 130.751938/3718, current 130.770996/3719: elapsed=19064.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 130.770996
last_received_ts = 130.770508
last_seq = 3719
current_ts = 130.790070
current_received_ts = 130.789429
current_seq = 3720
count = 326
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 130.770996/3719, current 130.790070/3720: elapsed=19066.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 130.770996/3719, current 130.790070/3720: elapsed=19066.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 130.790070
last_received_ts = 130.789429
last_seq = 3720
current_ts = 130.809143
current_received_ts = 130.808502
current_seq = 3721
count = 327
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 130.790070/3720, current 130.809143/3721: elapsed=19067.0us expected=19172.1us +- 95.9us, error 0.5%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 130.790070/3720, current 130.809143/3721: elapsed=19067.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 130.809143
last_received_ts = 130.808502
last_seq = 3721
current_ts = 130.828201
current_received_ts = 130.827560
current_seq = 3722
count = 328
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 130.809143/3721, current 130.828201/3722: elapsed=19065.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 130.809143/3721, current 130.828201/3722: elapsed=19065.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 130.828201
last_received_ts = 130.827560
last_seq = 3722
current_ts = 130.847260
current_received_ts = 130.846619
current_seq = 3723
count = 329
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 130.828201/3722, current 130.847260/3723: elapsed=19063.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 130.828201/3722, current 130.847260/3723: elapsed=19063.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 130.847260
last_received_ts = 130.846619
last_seq = 3723
current_ts = 130.866333
current_received_ts = 130.865692
current_seq = 3724
count = 330
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 130.847260/3723, current 130.866333/3724: elapsed=19063.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 130.847260/3723, current 130.866333/3724: elapsed=19063.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 130.866333
last_received_ts = 130.865692
last_seq = 3724
current_ts = 130.885391
current_received_ts = 130.884720
current_seq = 3725
count = 331
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 130.866333/3724, current 130.885391/3725: elapsed=19057.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 130.866333/3724, current 130.885391/3725: elapsed=19057.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 130.885391
last_received_ts = 130.884720
last_seq = 3725
current_ts = 130.904449
current_received_ts = 130.903793
current_seq = 3726
count = 332
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 130.885391/3725, current 130.904449/3726: elapsed=19067.0us expected=19172.1us +- 95.9us, error 0.5%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 130.885391/3725, current 130.904449/3726: elapsed=19067.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 130.904449
last_received_ts = 130.903793
last_seq = 3726
current_ts = 130.923492
current_received_ts = 130.922852
current_seq = 3727
count = 333
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 130.904449/3726, current 130.923492/3727: elapsed=19045.0us expected=19172.1us +- 95.9us, error 0.7%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 130.904449/3726, current 130.923492/3727: elapsed=19045.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 130.923492
last_received_ts = 130.922852
last_seq = 3727
current_ts = 130.942551
current_received_ts = 130.941910
current_seq = 3728
count = 334
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 130.923492/3727, current 130.942551/3728: elapsed=19054.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 130.923492/3727, current 130.942551/3728: elapsed=19054.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 130.942551
last_received_ts = 130.941910
last_seq = 3728
current_ts = 130.961609
current_received_ts = 130.960968
current_seq = 3729
count = 335
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 130.942551/3728, current 130.961609/3729: elapsed=19063.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 130.942551/3728, current 130.961609/3729: elapsed=19063.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 130.961609
last_received_ts = 130.960968
last_seq = 3729
current_ts = 130.980667
current_received_ts = 130.980057
current_seq = 3730
count = 336
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 130.961609/3729, current 130.980667/3730: elapsed=19057.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 130.961609/3729, current 130.980667/3730: elapsed=19057.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 130.980667
last_received_ts = 130.980057
last_seq = 3730
current_ts = 130.999725
current_received_ts = 130.999161
current_seq = 3731
count = 337
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 130.980667/3730, current 130.999725/3731: elapsed=19056.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 130.980667/3730, current 130.999725/3731: elapsed=19056.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 130.999725
last_received_ts = 130.999161
last_seq = 3731
current_ts = 131.018799
current_received_ts = 131.018204
current_seq = 3732
count = 338
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 130.999725/3731, current 131.018799/3732: elapsed=19068.0us expected=19172.1us +- 95.9us, error 0.5%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 130.999725/3731, current 131.018799/3732: elapsed=19068.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.018799
last_received_ts = 131.018204
last_seq = 3732
current_ts = 131.037842
current_received_ts = 131.037247
current_seq = 3733
count = 339
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.018799/3732, current 131.037842/3733: elapsed=19041.0us expected=19172.1us +- 95.9us, error 0.7%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.018799/3732, current 131.037842/3733: elapsed=19041.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.037842
last_received_ts = 131.037247
last_seq = 3733
current_ts = 131.056900
current_received_ts = 131.056305
current_seq = 3734
count = 340
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.037842/3733, current 131.056900/3734: elapsed=19060.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.037842/3733, current 131.056900/3734: elapsed=19060.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.056900
last_received_ts = 131.056305
last_seq = 3734
current_ts = 131.075958
current_received_ts = 131.075409
current_seq = 3735
count = 341
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.056900/3734, current 131.075958/3735: elapsed=19058.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.056900/3734, current 131.075958/3735: elapsed=19058.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.075958
last_received_ts = 131.075409
last_seq = 3735
current_ts = 131.095016
current_received_ts = 131.094360
current_seq = 3736
count = 342
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.075958/3735, current 131.095016/3736: elapsed=19062.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.075958/3735, current 131.095016/3736: elapsed=19062.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.095016
last_received_ts = 131.094360
last_seq = 3736
current_ts = 131.114075
current_received_ts = 131.113464
current_seq = 3737
count = 343
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.095016/3736, current 131.114075/3737: elapsed=19055.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.095016/3736, current 131.114075/3737: elapsed=19055.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.114075
last_received_ts = 131.113464
last_seq = 3737
current_ts = 131.133133
current_received_ts = 131.132553
current_seq = 3738
count = 344
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.114075/3737, current 131.133133/3738: elapsed=19060.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.114075/3737, current 131.133133/3738: elapsed=19060.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.133133
last_received_ts = 131.132553
last_seq = 3738
current_ts = 131.152176
current_received_ts = 131.151550
current_seq = 3739
count = 345
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.133133/3738, current 131.152176/3739: elapsed=19048.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.133133/3738, current 131.152176/3739: elapsed=19048.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.152176
last_received_ts = 131.151550
last_seq = 3739
current_ts = 131.171234
current_received_ts = 131.170609
current_seq = 3740
count = 346
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.152176/3739, current 131.171234/3740: elapsed=19058.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.152176/3739, current 131.171234/3740: elapsed=19058.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.171234
last_received_ts = 131.170609
last_seq = 3740
current_ts = 131.190308
current_received_ts = 131.189651
current_seq = 3741
count = 347
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.171234/3740, current 131.190308/3741: elapsed=19072.0us expected=19172.1us +- 95.9us, error 0.5%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.171234/3740, current 131.190308/3741: elapsed=19072.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.190308
last_received_ts = 131.189651
last_seq = 3741
current_ts = 131.209366
current_received_ts = 131.208740
current_seq = 3742
count = 348
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.190308/3741, current 131.209366/3742: elapsed=19057.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.190308/3741, current 131.209366/3742: elapsed=19057.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.209366
last_received_ts = 131.208740
last_seq = 3742
current_ts = 131.228409
current_received_ts = 131.227753
current_seq = 3743
count = 349
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.209366/3742, current 131.228409/3743: elapsed=19051.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.209366/3742, current 131.228409/3743: elapsed=19051.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.228409
last_received_ts = 131.227753
last_seq = 3743
current_ts = 131.247467
current_received_ts = 131.246811
current_seq = 3744
count = 350
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.228409/3743, current 131.247467/3744: elapsed=19053.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.228409/3743, current 131.247467/3744: elapsed=19053.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.247467
last_received_ts = 131.246811
last_seq = 3744
current_ts = 131.266525
current_received_ts = 131.265884
current_seq = 3745
count = 351
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.247467/3744, current 131.266525/3745: elapsed=19053.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.247467/3744, current 131.266525/3745: elapsed=19053.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.266525
last_received_ts = 131.265884
last_seq = 3745
current_ts = 131.285583
current_received_ts = 131.284927
current_seq = 3746
count = 352
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.266525/3745, current 131.285583/3746: elapsed=19061.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.266525/3745, current 131.285583/3746: elapsed=19061.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.285583
last_received_ts = 131.284927
last_seq = 3746
current_ts = 131.304657
current_received_ts = 131.304504
current_seq = 3747
count = 353
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.285583/3746, current 131.304657/3747: elapsed=19068.0us expected=19172.1us +- 95.9us, error 0.5%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.285583/3746, current 131.304657/3747: elapsed=19068.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.304657
last_received_ts = 131.304504
last_seq = 3747
current_ts = 131.323700
current_received_ts = 131.323029
current_seq = 3748
count = 354
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.304657/3747, current 131.323700/3748: elapsed=19056.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.304657/3747, current 131.323700/3748: elapsed=19056.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.323700
last_received_ts = 131.323029
last_seq = 3748
current_ts = 131.342758
current_received_ts = 131.342087
current_seq = 3749
count = 355
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.323700/3748, current 131.342758/3749: elapsed=19055.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.323700/3748, current 131.342758/3749: elapsed=19055.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.342758
last_received_ts = 131.342087
last_seq = 3749
current_ts = 131.361816
current_received_ts = 131.361160
current_seq = 3750
count = 356
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.342758/3749, current 131.361816/3750: elapsed=19053.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.342758/3749, current 131.361816/3750: elapsed=19053.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.361816
last_received_ts = 131.361160
last_seq = 3750
current_ts = 131.380859
current_received_ts = 131.380157
current_seq = 3751
count = 357
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.361816/3750, current 131.380859/3751: elapsed=19050.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.361816/3750, current 131.380859/3751: elapsed=19050.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.380859
last_received_ts = 131.380157
last_seq = 3751
current_ts = 131.399933
current_received_ts = 131.399231
current_seq = 3752
count = 358
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.380859/3751, current 131.399933/3752: elapsed=19064.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.380859/3751, current 131.399933/3752: elapsed=19064.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.399933
last_received_ts = 131.399231
last_seq = 3752
current_ts = 131.418991
current_received_ts = 131.418335
current_seq = 3753
count = 359
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.399933/3752, current 131.418991/3753: elapsed=19065.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.399933/3752, current 131.418991/3753: elapsed=19065.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.418991
last_received_ts = 131.418335
last_seq = 3753
current_ts = 131.438049
current_received_ts = 131.437439
current_seq = 3754
count = 360
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.418991/3753, current 131.438049/3754: elapsed=19049.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.418991/3753, current 131.438049/3754: elapsed=19049.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.438049
last_received_ts = 131.437439
last_seq = 3754
current_ts = 131.457108
current_received_ts = 131.456467
current_seq = 3755
count = 361
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.438049/3754, current 131.457108/3755: elapsed=19065.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.438049/3754, current 131.457108/3755: elapsed=19065.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.457108
last_received_ts = 131.456467
last_seq = 3755
current_ts = 131.476151
current_received_ts = 131.475464
current_seq = 3756
count = 362
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.457108/3755, current 131.476151/3756: elapsed=19050.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.457108/3755, current 131.476151/3756: elapsed=19050.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.476151
last_received_ts = 131.475464
last_seq = 3756
current_ts = 131.495209
current_received_ts = 131.494522
current_seq = 3757
count = 363
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.476151/3756, current 131.495209/3757: elapsed=19057.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.476151/3756, current 131.495209/3757: elapsed=19057.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.495209
last_received_ts = 131.494522
last_seq = 3757
current_ts = 131.514267
current_received_ts = 131.513565
current_seq = 3758
count = 364
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.495209/3757, current 131.514267/3758: elapsed=19052.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.495209/3757, current 131.514267/3758: elapsed=19052.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.514267
last_received_ts = 131.513565
last_seq = 3758
current_ts = 131.533325
current_received_ts = 131.532639
current_seq = 3759
count = 365
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.514267/3758, current 131.533325/3759: elapsed=19062.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.514267/3758, current 131.533325/3759: elapsed=19062.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.533325
last_received_ts = 131.532639
last_seq = 3759
current_ts = 131.552383
current_received_ts = 131.551682
current_seq = 3760
count = 366
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.533325/3759, current 131.552383/3760: elapsed=19058.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.533325/3759, current 131.552383/3760: elapsed=19058.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.552383
last_received_ts = 131.551682
last_seq = 3760
current_ts = 131.571442
current_received_ts = 131.570740
current_seq = 3761
count = 367
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.552383/3760, current 131.571442/3761: elapsed=19057.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.552383/3760, current 131.571442/3761: elapsed=19057.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.571442
last_received_ts = 131.570740
last_seq = 3761
current_ts = 131.590500
current_received_ts = 131.589813
current_seq = 3762
count = 368
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.571442/3761, current 131.590500/3762: elapsed=19061.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.571442/3761, current 131.590500/3762: elapsed=19061.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.590500
last_received_ts = 131.589813
last_seq = 3762
current_ts = 131.609543
current_received_ts = 131.608887
current_seq = 3763
count = 369
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.590500/3762, current 131.609543/3763: elapsed=19045.0us expected=19172.1us +- 95.9us, error 0.7%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.590500/3762, current 131.609543/3763: elapsed=19045.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.609543
last_received_ts = 131.608887
last_seq = 3763
current_ts = 131.628616
current_received_ts = 131.627914
current_seq = 3764
count = 370
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.609543/3763, current 131.628616/3764: elapsed=19066.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.609543/3763, current 131.628616/3764: elapsed=19066.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.628616
last_received_ts = 131.627914
last_seq = 3764
current_ts = 131.647659
current_received_ts = 131.647003
current_seq = 3765
count = 371
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.628616/3764, current 131.647659/3765: elapsed=19049.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.628616/3764, current 131.647659/3765: elapsed=19049.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.647659
last_received_ts = 131.647003
last_seq = 3765
current_ts = 131.666733
current_received_ts = 131.666046
current_seq = 3766
count = 372
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.647659/3765, current 131.666733/3766: elapsed=19065.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.647659/3765, current 131.666733/3766: elapsed=19065.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.666733
last_received_ts = 131.666046
last_seq = 3766
current_ts = 131.685791
current_received_ts = 131.685059
current_seq = 3767
count = 373
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.666733/3766, current 131.685791/3767: elapsed=19062.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.666733/3766, current 131.685791/3767: elapsed=19062.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.685791
last_received_ts = 131.685059
last_seq = 3767
current_ts = 131.704849
current_received_ts = 131.704193
current_seq = 3768
count = 374
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.685791/3767, current 131.704849/3768: elapsed=19059.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.685791/3767, current 131.704849/3768: elapsed=19059.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.704849
last_received_ts = 131.704193
last_seq = 3768
current_ts = 131.723892
current_received_ts = 131.723267
current_seq = 3769
count = 375
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.704849/3768, current 131.723892/3769: elapsed=19047.0us expected=19172.1us +- 95.9us, error 0.7%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.704849/3768, current 131.723892/3769: elapsed=19047.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.723892
last_received_ts = 131.723267
last_seq = 3769
current_ts = 131.742950
current_received_ts = 131.742325
current_seq = 3770
count = 376
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.723892/3769, current 131.742950/3770: elapsed=19053.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.723892/3769, current 131.742950/3770: elapsed=19053.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.742950
last_received_ts = 131.742325
last_seq = 3770
current_ts = 131.762009
current_received_ts = 131.761353
current_seq = 3771
count = 377
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.742950/3770, current 131.762009/3771: elapsed=19056.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.742950/3770, current 131.762009/3771: elapsed=19056.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.762009
last_received_ts = 131.761353
last_seq = 3771
current_ts = 131.781067
current_received_ts = 131.780472
current_seq = 3772
count = 378
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.762009/3771, current 131.781067/3772: elapsed=19066.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.762009/3771, current 131.781067/3772: elapsed=19066.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.781067
last_received_ts = 131.780472
last_seq = 3772
current_ts = 131.800125
current_received_ts = 131.799500
current_seq = 3773
count = 379
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.781067/3772, current 131.800125/3773: elapsed=19059.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.781067/3772, current 131.800125/3773: elapsed=19059.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.800125
last_received_ts = 131.799500
last_seq = 3773
current_ts = 131.819183
current_received_ts = 131.818481
current_seq = 3774
count = 380
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.800125/3773, current 131.819183/3774: elapsed=19054.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.800125/3773, current 131.819183/3774: elapsed=19054.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.819183
last_received_ts = 131.818481
last_seq = 3774
current_ts = 131.838242
current_received_ts = 131.837540
current_seq = 3775
count = 381
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.819183/3774, current 131.838242/3775: elapsed=19059.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.819183/3774, current 131.838242/3775: elapsed=19059.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.838242
last_received_ts = 131.837540
last_seq = 3775
current_ts = 131.857300
current_received_ts = 131.856598
current_seq = 3776
count = 382
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.838242/3775, current 131.857300/3776: elapsed=19050.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.838242/3775, current 131.857300/3776: elapsed=19050.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.857300
last_received_ts = 131.856598
last_seq = 3776
current_ts = 131.876343
current_received_ts = 131.875641
current_seq = 3777
count = 383
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.857300/3776, current 131.876343/3777: elapsed=19047.0us expected=19172.1us +- 95.9us, error 0.7%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.857300/3776, current 131.876343/3777: elapsed=19047.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.876343
last_received_ts = 131.875641
last_seq = 3777
current_ts = 131.895386
current_received_ts = 131.894684
current_seq = 3778
count = 384
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.876343/3777, current 131.895386/3778: elapsed=19048.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.876343/3777, current 131.895386/3778: elapsed=19048.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.895386
last_received_ts = 131.894684
last_seq = 3778
current_ts = 131.914444
current_received_ts = 131.913742
current_seq = 3779
count = 385
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.895386/3778, current 131.914444/3779: elapsed=19048.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.895386/3778, current 131.914444/3779: elapsed=19048.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.914444
last_received_ts = 131.913742
last_seq = 3779
current_ts = 131.933487
current_received_ts = 131.932785
current_seq = 3780
count = 386
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.914444/3779, current 131.933487/3780: elapsed=19048.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.914444/3779, current 131.933487/3780: elapsed=19048.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.933487
last_received_ts = 131.932785
last_seq = 3780
current_ts = 131.952530
current_received_ts = 131.951843
current_seq = 3781
count = 387
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.933487/3780, current 131.952530/3781: elapsed=19048.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.933487/3780, current 131.952530/3781: elapsed=19048.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.952530
last_received_ts = 131.951843
last_seq = 3781
current_ts = 131.971573
current_received_ts = 131.970886
current_seq = 3782
count = 388
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.952530/3781, current 131.971573/3782: elapsed=19046.0us expected=19172.1us +- 95.9us, error 0.7%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.952530/3781, current 131.971573/3782: elapsed=19046.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.971573
last_received_ts = 131.970886
last_seq = 3782
current_ts = 131.990631
current_received_ts = 131.989944
current_seq = 3783
count = 389
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.971573/3782, current 131.990631/3783: elapsed=19050.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.971573/3782, current 131.990631/3783: elapsed=19050.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 131.990631
last_received_ts = 131.989944
last_seq = 3783
current_ts = 132.009674
current_received_ts = 132.008987
current_seq = 3784
count = 390
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 131.990631/3783, current 132.009674/3784: elapsed=19048.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 131.990631/3783, current 132.009674/3784: elapsed=19048.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 132.009674
last_received_ts = 132.008987
last_seq = 3784
current_ts = 132.028717
current_received_ts = 132.028046
current_seq = 3785
count = 391
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 132.009674/3784, current 132.028717/3785: elapsed=19046.0us expected=19172.1us +- 95.9us, error 0.7%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 132.009674/3784, current 132.028717/3785: elapsed=19046.0us expected=19172.1us
(kms_flip:2394) DEBUG: name = flip
last_ts = 132.028717
last_received_ts = 132.028046
last_seq = 3785
current_ts = 132.047775
current_received_ts = 132.047104
current_seq = 3786
count = 392
seq_step = 1
(kms_flip:2394) DEBUG: flip ts/seq: last 132.028717/3785, current 132.047775/3786: elapsed=19055.0us expected=19172.1us +- 95.9us, error 0.6%
(kms_flip:2394) DEBUG: inconsistent flip ts/seq: last 132.028717/3785, current 132.047775/3786: elapsed=19055.0us expected=19172.1us
(kms_flip:2394) INFO: Event flip: expected 391, counted 393, passrate = 68.19%, encoder type 2
(kms_flip:2394) DEBUG: dropped frames, expected 391, counted 393, passrate = 68.19%, encoder type 2
(kms_flip:2394) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1709:
(kms_flip:2394) CRITICAL: Failed assertion: !retried
(kms_flip:2394) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:2394) igt_core-INFO: Stack trace:
(kms_flip:2394) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2394) igt_core-INFO:   #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
(kms_flip:2394) igt_core-INFO:   #2 ../tests/kms_flip.c:1889 run_test()
(kms_flip:2394) igt_core-INFO:   #3 ../tests/kms_flip.c:2158 __igt_unique____real_main2045()
(kms_flip:2394) igt_core-INFO:   #4 ../tests/kms_flip.c:2045 main()
(kms_flip:2394) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2394) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2394) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-HDMI-A1: FAIL (18.729s)
Dmesg
<6> [188.660530] Console: switching to colour dummy device 80x25
<6> [188.660599] [IGT] kms_flip: executing
<7> [188.663286] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [188.663968] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [188.664246] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [188.674653] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:596:DP-1]
<7> [188.674662] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:596:DP-1]
<7> [188.674848] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling TC_cold_off
<7> [188.675051] i915 0000:00:02.0: [drm:tgl_tc_cold_request [i915]] TC cold block succeeded
<7> [188.675328] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling TC_cold_off
<7> [188.675500] i915 0000:00:02.0: [drm:tgl_tc_cold_request [i915]] TC cold unblock succeeded
<7> [188.675682] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling TC_cold_off
<7> [188.675851] i915 0000:00:02.0: [drm:tgl_tc_cold_request [i915]] TC cold block succeeded
<7> [188.676153] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port D/TC#1: TC port mode reset (disconnected -> legacy) pin assignment: - max lanes: 4
<7> [188.680298] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:596:DP-1] disconnected
<7> [188.680696] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:606:DP-2]
<7> [188.680700] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:606:DP-2]
<7> [188.681044] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port E/TC#2: TC port mode reset (disconnected -> tbt-alt) pin assignment: - max lanes: 4
<7> [188.681300] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:606:DP-2] disconnected
<7> [188.681670] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:615:DP-3]
<7> [188.681674] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:615:DP-3]
<7> [188.682020] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port F/TC#3: TC port mode reset (disconnected -> tbt-alt) pin assignment: - max lanes: 4
<7> [188.682270] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:615:DP-3] disconnected
<7> [188.682639] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:624:DP-4]
<7> [188.682643] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:624:DP-4]
<7> [188.682986] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port G/TC#4: TC port mode reset (disconnected -> tbt-alt) pin assignment: - max lanes: 4
<7> [188.683237] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:624:DP-4] disconnected
<7> [188.683815] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:586:HDMI-A-1]
<7> [188.683822] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:586:HDMI-A-1]
<7> [188.710661] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:586:HDMI-A-1] Supported Monitor Refresh rate range is 0 Hz - 0 Hz
<7> [188.710670] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:586:HDMI-A-1] Assigning EDID-1.4 digital sink color depth as 8 bpc.
<7> [188.710673] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:586:HDMI-A-1] ELD monitor fitHeadless4k
<7> [188.710675] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:586:HDMI-A-1] ELD size 36, SAD count 0
<7> [188.711107] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0040 w(1)
<7> [188.711252] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [188.711788] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0040 w(1)
<7> [188.711948] i915 0000:00:02.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [188.712394] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "1366x768": 216 268000 1366 1414 1446 1526 768 771 777 814 0x40 0x6 (CLOCK_HIGH)
<7> [188.712406] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080i": 60 74250 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x15 (NO_INTERLACE)
<7> [188.712415] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080i": 60 74176 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x15 (NO_INTERLACE)
<7> [188.712425] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:586:HDMI-A-1] probed modes:
<7> [188.712430] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [188.712436] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "4088x2304": 10 108000 4088 4152 4280 4536 2304 2305 2308 2340 0x40 0x5
<7> [188.712442] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 15 128430 3840 3889 3921 4000 2160 2163 2169 2195 0x40 0xa
<7> [188.712448] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2880x1800": 11 60000 2880 2888 2984 3040 1800 1802 1820 1845 0x40 0xa
<7> [188.712454] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1600": 13 60000 2560 2568 2664 2720 1600 1602 1620 1645 0x40 0xa
<7> [188.712460] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1440": 15 60000 2560 2568 2664 2720 1440 1442 1460 1485 0x40 0xa
<7> [188.712465] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2048x1536": 17 60000 2048 2056 2152 2208 1536 1538 1556 1581 0x40 0xa
<7> [188.712471] 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> [188.712477] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1680x1050": 60 146250 1680 1784 1960 2240 1050 1053 1059 1089 0x40 0x6
<7> [188.712482] 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> [188.712488] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x1024": 75 135000 1280 1296 1440 1688 1024 1025 1028 1066 0x40 0x5
<7> [188.712494] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1360x768": 60 84963 1366 1431 1567 1776 768 771 781 798 0x0 0x6
<7> [188.712499] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1360x768": 20 25190 1360 1368 1464 1520 768 770 788 813 0x40 0xa
<7> [188.712505] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x800": 60 83500 1280 1352 1480 1680 800 803 809 831 0x40 0x6
<7> [188.712511] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1152x864": 75 108000 1152 1216 1344 1600 864 865 868 900 0x40 0x5
<7> [188.712517] 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> [188.712523] 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> [188.712529] 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> [188.712534] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 75 78750 1024 1040 1136 1312 768 769 772 800 0x40 0x5
<7> [188.712540] 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> [188.712545] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1440x480": 60 54054 1440 1472 1596 1716 480 489 495 525 0x40 0xa
<7> [188.712551] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1440x480": 60 54000 1440 1472 1596 1716 480 489 495 525 0x40 0xa
<7> [188.712557] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x576": 60 46560 1024 1064 1160 1296 576 579 584 599 0x0 0x6
<7> [188.712562] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "832x624": 75 57284 832 864 928 1152 624 625 628 667 0x40 0xa
<7> [188.712568] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 75 49500 800 816 896 1056 600 601 604 625 0x40 0x5
<7> [188.712574] 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> [188.712579] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 75 31500 640 656 720 840 480 481 484 500 0x40 0xa
<7> [188.712585] 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> [188.712591] 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> [188.712596] 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> [188.712602] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "720x400": 70 28320 720 738 846 900 400 412 414 449 0x40 0x6
<7> [188.712646] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:596:DP-1]
<7> [188.712651] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:596:DP-1]
<7> [188.716827] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:596:DP-1] disconnected
<7> [188.716844] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:606:DP-2]
<7> [188.716848] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:606:DP-2]
<7> [188.717196] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:606:DP-2] disconnected
<7> [188.717210] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:615:DP-3]
<7> [188.717213] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:615:DP-3]
<7> [188.717527] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:615:DP-3] disconnected
<7> [188.717540] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:624:DP-4]
<7> [188.717543] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:624:DP-4]
<7> [188.717856] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:624:DP-4] disconnected
<6> [188.722413] [IGT] kms_flip: starting subtest plain-flip-ts-check-interruptible
<6> [188.724972] [IGT] kms_flip: starting dynamic subtest A-HDMI-A1
<7> [188.725373] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:635]
<7> [188.725464] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:636]
<7> [188.752843] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:169:pipe A]
<7> [188.752952] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [188.753151] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [188.753316] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [188.753479] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [188.753658] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [188.753889] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [188.754046] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [188.754202] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2360, found 0)
<7> [188.754359] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [188.754516] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2360, found 0)
<7> [188.754767] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [188.754934] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2096, found 0)
<7> [188.755117] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [188.755277] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [188.755436] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [188.755621] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [188.755789] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [188.755943] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [188.756097] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [188.756253] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2360, found 0)
<7> [188.756407] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [188.756594] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2360, found 0)
<7> [188.756760] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [188.756915] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2096, found 0)
<7> [188.757070] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [188.757231] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [188.757390] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [188.757577] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [188.757744] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [188.757919] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [188.758076] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [188.758232] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [188.758413] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [188.758579] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [188.758734] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 139930, found 0)
<7> [188.758890] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 139930, found 0)
<7> [188.759044] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in port_clock (expected 139930, found 0)
<7> [188.759200] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:169:pipe A] fastset requirement not met, forcing full modeset
<7> [188.759381] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:169:pipe A] releasing DPLL 0
<7> [188.759701] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [188.759859] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:169:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [188.760046] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:33:plane 1A] ddb ( 0 - 2016) -> ( 0 - 0), size 2016 -> 0
<7> [188.760199] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:163:cursor A] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0
<7> [188.760372] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [188.760540] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] lines 1, 4, 4, 4, 4, 5, 7, 7, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [188.760693] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] blocks 16, 65, 65, 65, 65, 81, 113, 113, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [188.760847] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] min_ddb 19, 73, 73, 73, 73, 91, 126, 126, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [188.761021] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:169:pipe A] data rate 0 num active planes 0
<7> [188.761181] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 0
<7> [188.761382] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:169:pipe A] min cdclk: 69965 kHz -> 0 kHz
<7> [188.761568] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 8746 kHz -> 0 kHz
<7> [188.761747] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:169:pipe A] enable: no [modeset]
<7> [188.761908] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:33:plane 1A] fb: [NOFB], visible: no
<7> [188.762067] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:63:plane 2A] fb: [NOFB], visible: no
<7> [188.762265] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:93:plane 3A] fb: [NOFB], visible: no
<7> [188.762425] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:123:plane 4A] fb: [NOFB], visible: no
<7> [188.762581] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:133:plane 5A] fb: [NOFB], visible: no
<7> [188.762736] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:143:plane 6A] fb: [NOFB], visible: no
<7> [188.762894] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:153:plane 7A] fb: [NOFB], visible: no
<7> [188.763049] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:163:cursor A] fb: [NOFB], visible: no
<7> [188.763294] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [188.763459] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [188.776621] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [188.794912] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [188.795248] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:169:pipe A]
<7> [188.795561] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [188.795880] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:33:plane 1A]
<7> [188.796184] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:585:DDI B/PHY B]
<7> [188.796474] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:595:DDI TC1/PHY TC1]
<7> [188.796755] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:597:DP-MST A]
<7> [188.797031] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:598:DP-MST B]
<7> [188.797307] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST C]
<7> [188.797597] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST D]
<7> [188.797938] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:605:DDI TC2/PHY TC2]
<7> [188.798258] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:607:DP-MST A]
<7> [188.798554] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:608:DP-MST B]
<7> [188.798885] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST C]
<7> [188.799236] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST D]
<7> [188.799525] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:614:DDI TC3/PHY TC3]
<7> [188.799868] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:616:DP-MST A]
<7> [188.800167] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:617:DP-MST B]
<7> [188.800428] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST C]
<7> [188.800717] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST D]
<7> [188.800996] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:623:DDI TC4/PHY TC4]
<7> [188.801258] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DP-MST A]
<7> [188.801538] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST B]
<7> [188.801815] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST C]
<7> [188.802076] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST D]
<7> [188.802345] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:586:HDMI-A-1]
<7> [188.802932] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [188.803267] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:169:pipe A]
<7> [188.803988] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:307:pipe B]
<7> [188.804184] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:445:pipe C]
<7> [188.804346] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:583:pipe D]
<7> [188.804553] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:169:pipe A]
<7> [188.804591] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:586:HDMI-A-1]
<7> [188.804669] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:586:HDMI-A-1] Limiting display bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [188.804884] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [188.805079] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:169:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [188.805283] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:585:DDI B/PHY B] [CRTC:169:pipe A]
<7> [188.805514] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [188.805736] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [188.805940] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [188.806135] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [188.806341] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [188.806510] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [188.806667] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [188.806824] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2360)
<7> [188.806980] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [188.807137] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2360)
<7> [188.807314] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [188.807484] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2096)
<7> [188.807641] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [188.807795] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [188.807951] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [188.808106] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [188.808282] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [188.808449] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [188.808604] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [188.808758] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2360)
<7> [188.808913] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [188.809083] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2360)
<7> [188.809258] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [188.809428] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2096)
<7> [188.809582] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [188.809736] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [188.809891] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [188.810045] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [188.810216] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [188.810385] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [188.810539] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [188.810693] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [188.810845] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [188.811001] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [188.811176] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 139930)
<7> [188.811344] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 139930)
<7> [188.811500] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in port_clock (expected 0, found 139930)
<7> [188.811656] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:169:pipe A] fastset requirement not met, forcing full modeset
<7> [188.811835] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [188.811992] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:169:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x1
<7> [188.812215] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:33:plane 1A] ddb ( 0 - 0) -> ( 0 - 2016), size 0 -> 2016
<7> [188.812372] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:163:cursor A] ddb ( 0 - 0) -> (2016 - 2048), size 0 -> 32
<7> [188.812541] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [188.812693] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 4, 4, 4, 4, 5, 7, 7, 0, 0, 0
<7> [188.812849] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 65, 65, 65, 65, 81, 113, 113, 30, 0, 0
<7> [188.813015] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 73, 73, 73, 73, 91, 126, 126, 31, 0, 0
<7> [188.813200] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:169:pipe A] data rate 559720 num active planes 1
<7> [188.813361] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 560
<7> [188.813520] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [188.813695] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:169:pipe A] min cdclk: 0 kHz -> 69965 kHz
<7> [188.813870] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 8746 kHz
<7> [188.814045] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:169:pipe A] allocated DPLL 0
<7> [188.814221] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:169:pipe A] reserving DPLL 0
<7> [188.814385] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:169:pipe A] enable: yes [modeset]
<7> [188.814547] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [188.814709] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [188.814868] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [188.815049] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [188.815216] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [188.815370] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [188.815525] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [188.815682] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [188.815836] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [188.816106] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 42, guardband: 44 vsync start: 0, vsync end: 0
<7> [188.816263] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1081, vmax vblank: 1081, vmin vtotal: 1125, vmax vtotal: 1125
<7> [188.816419] 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> [188.816574] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [188.816729] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [188.816901] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139930, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [188.817070] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x40 0x5
<7> [188.817224] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139930, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [188.817380] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 139930, pipe src: 1920x1080+0+0, pixel rate 139930, min cdclk 69965
<7> [188.817535] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0
<7> [188.817690] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [188.817857] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [188.818029] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [188.818189] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x4801b5, cfgcr1: 0xe84, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [188.818348] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [188.818504] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [188.818660] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [188.818831] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [188.818997] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [188.819150] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [188.819305] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [188.819458] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [188.819611] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [188.819798] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [188.819966] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [188.820117] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [188.820269] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [188.820421] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:33:plane 1A] fb: [FB:635] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [188.820575] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [188.820750] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [188.820918] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:63:plane 2A] fb: [NOFB], visible: no
<7> [188.821070] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:93:plane 3A] fb: [NOFB], visible: no
<7> [188.821224] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:123:plane 4A] fb: [NOFB], visible: no
<7> [188.821377] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:133:plane 5A] fb: [NOFB], visible: no
<7> [188.821530] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:143:plane 6A] fb: [NOFB], visible: no
<7> [188.821697] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:153:plane 7A] fb: [NOFB], visible: no
<7> [188.821863] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:163:cursor A] fb: [NOFB], visible: no
<7> [188.823043] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:585:DDI B/PHY B]
<7> [188.823208] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:595:DDI TC1/PHY TC1]
<7> [188.823369] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:597:DP-MST A]
<7> [188.823544] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:598:DP-MST B]
<7> [188.823712] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST C]
<7> [188.823870] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST D]
<7> [188.824025] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:605:DDI TC2/PHY TC2]
<7> [188.824179] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:607:DP-MST A]
<7> [188.824332] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:608:DP-MST B]
<7> [188.824495] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST C]
<7> [188.824659] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST D]
<7> [188.824830] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:614:DDI TC3/PHY TC3]
<7> [188.824981] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:616:DP-MST A]
<7> [188.825132] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:617:DP-MST B]
<7> [188.825283] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST C]
<7> [188.825433] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST D]
<7> [188.825604] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:623:DDI TC4/PHY TC4]
<7> [188.825775] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DP-MST A]
<7> [188.825931] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST B]
<7> [188.826087] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST C]
<7> [188.826240] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST D]
<7> [188.826538] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [188.826791] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:169:pipe A]
<7> [188.826954] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [188.827433] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [188.827634] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [188.832168] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [188.832338] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:33:plane 1A]
<7> [188.847225] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:586:HDMI-A-1]
<7> [188.847509] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:169:pipe A]
<7> [188.904187] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [188.904590] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [188.904922] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [189.172520] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [189.173029] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [189.725770] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port G/TC#4: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [189.725852] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port F/TC#3: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [189.725872] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port E/TC#2: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [189.725871] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling TC_cold_off
<7> [189.726371] i915 0000:00:02.0: [drm:tgl_tc_cold_request [i915]] TC cold unblock succeeded
<7> [189.726746] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port D/TC#1: TC port mode reset (legacy -> disconnected) pin assignment: - max lanes: 4
<7> [196.511321] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [196.511721] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [196.512070] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [199.458973] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:169:pipe A]
<7> [199.459221] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [199.459746] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [199.460189] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [199.460651] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [199.461131] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [199.461623] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [199.462072] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [199.462517] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2360, found 0)
<7> [199.462945] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [199.463380] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2360, found 0)
<7> [199.463806] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [199.464148] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2096, found 0)
<7> [199.464302] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [199.464470] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [199.464626] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [199.464782] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [199.464936] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [199.465090] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [199.465244] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [199.465408] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2360, found 0)
<7> [199.465564] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [199.465720] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2360, found 0)
<7> [199.465874] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [199.466029] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2096, found 0)
<7> [199.466187] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [199.466353] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [199.466512] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [199.466667] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [199.466823] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [199.466978] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [199.467132] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [199.467289] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [199.467459] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [199.467616] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [199.467772] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 139930, found 0)
<7> [199.467927] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 139930, found 0)
<7> [199.468082] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in port_clock (expected 139930, found 0)
<7> [199.468239] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:169:pipe A] fastset requirement not met, forcing full modeset
<7> [199.468407] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:169:pipe A] releasing DPLL 0
<7> [199.468594] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [199.468750] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:169:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [199.468935] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:33:plane 1A] ddb ( 0 - 2016) -> ( 0 - 0), size 2016 -> 0
<7> [199.469089] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:163:cursor A] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0
<7> [199.469245] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [199.469410] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] lines 1, 4, 4, 4, 4, 5, 7, 7, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [199.469563] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] blocks 16, 65, 65, 65, 65, 81, 113, 113, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [199.469716] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] min_ddb 19, 73, 73, 73, 73, 91, 126, 126, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [199.469888] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:169:pipe A] data rate 0 num active planes 0
<7> [199.470049] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 0
<7> [199.470224] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:169:pipe A] min cdclk: 69965 kHz -> 0 kHz
<7> [199.470413] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 8746 kHz -> 0 kHz
<7> [199.470576] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:169:pipe A] enable: no [modeset]
<7> [199.470737] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:33:plane 1A] fb: [NOFB], visible: no
<7> [199.470895] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:63:plane 2A] fb: [NOFB], visible: no
<7> [199.471051] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:93:plane 3A] fb: [NOFB], visible: no
<7> [199.471207] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:123:plane 4A] fb: [NOFB], visible: no
<7> [199.471372] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:133:plane 5A] fb: [NOFB], visible: no
<7> [199.471528] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:143:plane 6A] fb: [NOFB], visible: no
<7> [199.471681] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:153:plane 7A] fb: [NOFB], visible: no
<7> [199.471835] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:163:cursor A] fb: [NOFB], visible: no
<7> [199.472049] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [199.472213] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [199.494208] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [199.511522] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [199.511951] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:169:pipe A]
<7> [199.512351] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [199.512709] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:33:plane 1A]
<7> [199.513114] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:585:DDI B/PHY B]
<7> [199.513672] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:595:DDI TC1/PHY TC1]
<7> [199.514213] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:597:DP-MST A]
<7> [199.514722] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:598:DP-MST B]
<7> [199.515259] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST C]
<7> [199.515741] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST D]
<7> [199.516270] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:605:DDI TC2/PHY TC2]
<7> [199.516760] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:607:DP-MST A]
<7> [199.517305] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:608:DP-MST B]
<7> [199.517802] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST C]
<7> [199.518279] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST D]
<7> [199.518786] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:614:DDI TC3/PHY TC3]
<7> [199.519262] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:616:DP-MST A]
<7> [199.519755] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:617:DP-MST B]
<7> [199.520293] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST C]
<7> [199.520729] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST D]
<7> [199.521173] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:623:DDI TC4/PHY TC4]
<7> [199.521565] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DP-MST A]
<7> [199.521982] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST B]
<7> [199.522379] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST C]
<7> [199.522786] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST D]
<7> [199.523190] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:586:HDMI-A-1]
<7> [199.523933] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [199.524365] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:169:pipe A]
<7> [199.525539] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:307:pipe B]
<7> [199.525896] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:445:pipe C]
<7> [199.526203] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:583:pipe D]
<7> [199.526485] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:169:pipe A]
<7> [199.526535] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:586:HDMI-A-1]
<7> [199.526645] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:586:HDMI-A-1] Limiting display bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [199.526929] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [199.527156] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:169:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [199.527396] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:585:DDI B/PHY B] [CRTC:169:pipe A]
<7> [199.527624] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [199.527882] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [199.528119] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [199.528353] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [199.528589] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [199.528847] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [199.529083] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [199.529321] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2360)
<7> [199.529560] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [199.529809] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2360)
<7> [199.530044] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [199.530277] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2096)
<7> [199.530510] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [199.530759] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [199.530995] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [199.531230] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [199.531464] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [199.531705] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [199.531936] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [199.532168] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2360)
<7> [199.532400] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [199.532749] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2360)
<7> [199.532988] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [199.533226] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2096)
<7> [199.533461] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [199.533716] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [199.533948] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [199.534184] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [199.534416] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [199.534655] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [199.534886] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [199.535119] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [199.535353] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [199.535588] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [199.535847] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 139930)
<7> [199.536078] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 139930)
<7> [199.536310] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:169:pipe A] fastset requirement not met in port_clock (expected 0, found 139930)
<7> [199.536543] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:169:pipe A] fastset requirement not met, forcing full modeset
<7> [199.536849] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [199.537086] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:169:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x1
<7> [199.537386] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:33:plane 1A] ddb ( 0 - 0) -> ( 0 - 2016), size 0 -> 2016
<7> [199.537624] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:163:cursor A] ddb ( 0 - 0) -> (2016 - 2048), size 0 -> 32
<7> [199.537855] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [199.538085] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 4, 4, 4, 4, 5, 7, 7, 0, 0, 0
<7> [199.538314] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 65, 65, 65, 65, 81, 113, 113, 30, 0, 0
<7> [199.538544] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 73, 73, 73, 73, 91, 126, 126, 31, 0, 0
<7> [199.538832] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:169:pipe A] data rate 559720 num active planes 1
<7> [199.539072] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 560
<7> [199.539310] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [199.539579] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:169:pipe A] min cdclk: 0 kHz -> 69965 kHz
<7> [199.539842] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 8746 kHz
<7> [199.540078] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:169:pipe A] allocated DPLL 0
<7> [199.540319] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:169:pipe A] reserving DPLL 0
<7> [199.540574] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:169:pipe A] enable: yes [modeset]
<7> [199.540820] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [199.541059] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [199.541295] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [199.541528] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [199.541788] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [199.542021] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [199.542251] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [199.542486] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [199.542741] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [199.542977] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 42, guardband: 44 vsync start: 0, vsync end: 0
<7> [199.543208] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1081, vmax vblank: 1081, vmin vtotal: 1125, vmax vtotal: 1125
<7> [199.543439] 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> [199.543688] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [199.543924] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [199.544157] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139930, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [199.544392] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x40 0x5
<7> [199.544645] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139930, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [199.544879] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 139930, pipe src: 1920x1080+0+0, pixel rate 139930, min cdclk 69965
<7> [199.545111] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0
<7> [199.545340] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [199.545583] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [199.545820] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [199.546049] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x4801b5, cfgcr1: 0xe84, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [199.546283] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [199.546525] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [199.546755] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [199.546987] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [199.547219] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [199.547448] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [199.547703] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [199.547933] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [199.548168] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [199.548398] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [199.548649] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [199.548880] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [199.549109] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [199.549339] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:33:plane 1A] fb: [FB:635] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [199.549588] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [199.549818] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [199.550053] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:63:plane 2A] fb: [NOFB], visible: no
<7> [199.550282] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:93:plane 3A] fb: [NOFB], visible: no
<7> [199.550514] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:123:plane 4A] fb: [NOFB], visible: no
<7> [199.550674] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:133:plane 5A] fb: [NOFB], visible: no
<7> [199.550835] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:143:plane 6A] fb: [NOFB], visible: no
<7> [199.550992] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:153:plane 7A] fb: [NOFB], visible: no
<7> [199.551149] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:163:cursor A] fb: [NOFB], visible: no
<7> [199.551402] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:585:DDI B/PHY B]
<7> [199.551585] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:595:DDI TC1/PHY TC1]
<7> [199.551744] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:597:DP-MST A]
<7> [199.551901] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:598:DP-MST B]
<7> [199.552056] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST C]
<7> [199.552210] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST D]
<7> [199.552365] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:605:DDI TC2/PHY TC2]
<7> [199.552532] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:607:DP-MST A]
<7> [199.552725] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:608:DP-MST B]
<7> [199.552881] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST C]
<7> [199.553038] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST D]
<7> [199.553195] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:614:DDI TC3/PHY TC3]
<7> [199.553351] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:616:DP-MST A]
<7> [199.553521] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:617:DP-MST B]
<7> [199.553694] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST C]
<7> [199.553848] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST D]
<7> [199.554001] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:623:DDI TC4/PHY TC4]
<7> [199.554153] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DP-MST A]
<7> [199.554306] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST B]
<7> [199.554472] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST C]
<7> [199.554656] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST D]
<7> [199.554937] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [199.555192] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:169:pipe A]
<7> [199.555355] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [199.555603] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [199.555803] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [199.558229] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [199.558407] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:33:plane 1A]
<7> [199.575389] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:586:HDMI-A-1]
<7> [199.575655] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:169:pipe A]
<7> [199.631775] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [199.632170] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [199.632497] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [199.898572] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [199.898980] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [207.391404] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [207.391805] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [207.392154] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<6> [207.453894] [IGT] kms_flip: finished subtest A-HDMI-A1, FAIL
Created at 2026-01-12 21:19:27