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

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-snb5
Igt-Version
IGT-Version: 2.3-ge61a8b4ea (x86_64) (Linux: 6.19.0-rc5-CI_DRM_17806-geb2f46812a9a+ x86_64)
Out
Using IGT_SRANDOM=1768239759 for randomisation
Opened device: /dev/dri/card0
Using monotonic timestamps
Starting subtest: plain-flip-ts-check-interruptible
Starting dynamic subtest: A-VGA1
  1024x768: 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa 
Expected frametime: 16666us; measured 17419.6us +- 9.054us accuracy 0.16%
vblank interval differs from modeline! expected 16665.6us, measured 17420us +- 9.054us, difference 754.0us (83.3 sigma)
Event flip: expected 431, counted 438, passrate = 10.05%, encoder type 1
Expected frametime: 16666us; measured 16885.8us +- 8.683us accuracy 0.15%
vblank interval differs from modeline! expected 16665.6us, measured 16886us +- 8.683us, difference 220.2us (25.4 sigma)
Event flip: expected 444, counted 446, passrate = 69.96%, encoder type 1
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c: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-VGA1: FAIL (18.731s)
Err
Starting subtest: plain-flip-ts-check-interruptible
Starting dynamic subtest: A-VGA1
(kms_flip:2680) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1709:
(kms_flip:2680) CRITICAL: Failed assertion: !retried
(kms_flip:2680) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest A-VGA1 failed.
**** DEBUG ****
(kms_flip:2680) DEBUG: flip ts/seq: last 138.561584/3861, current 138.578400/3862: elapsed=16811.0us expected=16885.8us +- 84.4us, error 0.4%
(kms_flip:2680) DEBUG: name = flip
last_ts = 138.578400
last_received_ts = 138.577759
last_seq = 3862
current_ts = 138.595184
current_received_ts = 138.594589
current_seq = 3863
count = 364
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 138.578400/3862, current 138.595184/3863: elapsed=16784.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 138.578400/3862, current 138.595184/3863: elapsed=16784.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 138.595184
last_received_ts = 138.594589
last_seq = 3863
current_ts = 138.611984
current_received_ts = 138.611359
current_seq = 3864
count = 365
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 138.595184/3863, current 138.611984/3864: elapsed=16797.0us expected=16885.8us +- 84.4us, error 0.5%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 138.595184/3863, current 138.611984/3864: elapsed=16797.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 138.611984
last_received_ts = 138.611359
last_seq = 3864
current_ts = 138.628784
current_received_ts = 138.628113
current_seq = 3865
count = 366
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 138.611984/3864, current 138.628784/3865: elapsed=16805.0us expected=16885.8us +- 84.4us, error 0.5%
(kms_flip:2680) DEBUG: name = flip
last_ts = 138.628784
last_received_ts = 138.628113
last_seq = 3865
current_ts = 138.645569
current_received_ts = 138.644943
current_seq = 3866
count = 367
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 138.628784/3865, current 138.645569/3866: elapsed=16786.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 138.628784/3865, current 138.645569/3866: elapsed=16786.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 138.645569
last_received_ts = 138.644943
last_seq = 3866
current_ts = 138.662369
current_received_ts = 138.661774
current_seq = 3867
count = 368
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 138.645569/3866, current 138.662369/3867: elapsed=16799.0us expected=16885.8us +- 84.4us, error 0.5%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 138.645569/3866, current 138.662369/3867: elapsed=16799.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 138.662369
last_received_ts = 138.661774
last_seq = 3867
current_ts = 138.679169
current_received_ts = 138.678528
current_seq = 3868
count = 369
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 138.662369/3867, current 138.679169/3868: elapsed=16803.0us expected=16885.8us +- 84.4us, error 0.5%
(kms_flip:2680) DEBUG: name = flip
last_ts = 138.679169
last_received_ts = 138.678528
last_seq = 3868
current_ts = 138.695969
current_received_ts = 138.695297
current_seq = 3869
count = 370
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 138.679169/3868, current 138.695969/3869: elapsed=16790.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 138.679169/3868, current 138.695969/3869: elapsed=16790.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 138.695969
last_received_ts = 138.695297
last_seq = 3869
current_ts = 138.712769
current_received_ts = 138.712173
current_seq = 3870
count = 371
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 138.695969/3869, current 138.712769/3870: elapsed=16808.0us expected=16885.8us +- 84.4us, error 0.5%
(kms_flip:2680) DEBUG: name = flip
last_ts = 138.712769
last_received_ts = 138.712173
last_seq = 3870
current_ts = 138.729553
current_received_ts = 138.728943
current_seq = 3871
count = 372
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 138.712769/3870, current 138.729553/3871: elapsed=16775.0us expected=16885.8us +- 84.4us, error 0.7%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 138.712769/3870, current 138.729553/3871: elapsed=16775.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 138.729553
last_received_ts = 138.728943
last_seq = 3871
current_ts = 138.746338
current_received_ts = 138.745697
current_seq = 3872
count = 373
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 138.729553/3871, current 138.746338/3872: elapsed=16794.0us expected=16885.8us +- 84.4us, error 0.5%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 138.729553/3871, current 138.746338/3872: elapsed=16794.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 138.746338
last_received_ts = 138.745697
last_seq = 3872
current_ts = 138.763123
current_received_ts = 138.762527
current_seq = 3873
count = 374
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 138.746338/3872, current 138.763123/3873: elapsed=16782.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 138.746338/3872, current 138.763123/3873: elapsed=16782.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 138.763123
last_received_ts = 138.762527
last_seq = 3873
current_ts = 138.779907
current_received_ts = 138.779266
current_seq = 3874
count = 375
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 138.763123/3873, current 138.779907/3874: elapsed=16784.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 138.763123/3873, current 138.779907/3874: elapsed=16784.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 138.779907
last_received_ts = 138.779266
last_seq = 3874
current_ts = 138.796692
current_received_ts = 138.796051
current_seq = 3875
count = 376
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 138.779907/3874, current 138.796692/3875: elapsed=16790.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 138.779907/3874, current 138.796692/3875: elapsed=16790.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 138.796692
last_received_ts = 138.796051
last_seq = 3875
current_ts = 138.813492
current_received_ts = 138.812851
current_seq = 3876
count = 377
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 138.796692/3875, current 138.813492/3876: elapsed=16789.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 138.796692/3875, current 138.813492/3876: elapsed=16789.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 138.813492
last_received_ts = 138.812851
last_seq = 3876
current_ts = 138.830276
current_received_ts = 138.829651
current_seq = 3877
count = 378
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 138.813492/3876, current 138.830276/3877: elapsed=16797.0us expected=16885.8us +- 84.4us, error 0.5%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 138.813492/3876, current 138.830276/3877: elapsed=16797.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 138.830276
last_received_ts = 138.829651
last_seq = 3877
current_ts = 138.847061
current_received_ts = 138.846512
current_seq = 3878
count = 379
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 138.830276/3877, current 138.847061/3878: elapsed=16783.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 138.830276/3877, current 138.847061/3878: elapsed=16783.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 138.847061
last_received_ts = 138.846512
last_seq = 3878
current_ts = 138.863846
current_received_ts = 138.863235
current_seq = 3879
count = 380
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 138.847061/3878, current 138.863846/3879: elapsed=16784.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 138.847061/3878, current 138.863846/3879: elapsed=16784.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 138.863846
last_received_ts = 138.863235
last_seq = 3879
current_ts = 138.880646
current_received_ts = 138.880066
current_seq = 3880
count = 381
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 138.863846/3879, current 138.880646/3880: elapsed=16791.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 138.863846/3879, current 138.880646/3880: elapsed=16791.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 138.880646
last_received_ts = 138.880066
last_seq = 3880
current_ts = 138.897430
current_received_ts = 138.896820
current_seq = 3881
count = 382
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 138.880646/3880, current 138.897430/3881: elapsed=16788.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 138.880646/3880, current 138.897430/3881: elapsed=16788.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 138.897430
last_received_ts = 138.896820
last_seq = 3881
current_ts = 138.914230
current_received_ts = 138.913589
current_seq = 3882
count = 383
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 138.897430/3881, current 138.914230/3882: elapsed=16794.0us expected=16885.8us +- 84.4us, error 0.5%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 138.897430/3881, current 138.914230/3882: elapsed=16794.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 138.914230
last_received_ts = 138.913589
last_seq = 3882
current_ts = 138.931000
current_received_ts = 138.930420
current_seq = 3883
count = 384
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 138.914230/3882, current 138.931000/3883: elapsed=16781.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 138.914230/3882, current 138.931000/3883: elapsed=16781.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 138.931000
last_received_ts = 138.930420
last_seq = 3883
current_ts = 138.947784
current_received_ts = 138.947159
current_seq = 3884
count = 385
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 138.931000/3883, current 138.947784/3884: elapsed=16786.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 138.931000/3883, current 138.947784/3884: elapsed=16786.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 138.947784
last_received_ts = 138.947159
last_seq = 3884
current_ts = 138.964584
current_received_ts = 138.964035
current_seq = 3885
count = 386
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 138.947784/3884, current 138.964584/3885: elapsed=16796.0us expected=16885.8us +- 84.4us, error 0.5%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 138.947784/3884, current 138.964584/3885: elapsed=16796.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 138.964584
last_received_ts = 138.964035
last_seq = 3885
current_ts = 138.981369
current_received_ts = 138.980789
current_seq = 3886
count = 387
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 138.964584/3885, current 138.981369/3886: elapsed=16780.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 138.964584/3885, current 138.981369/3886: elapsed=16780.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 138.981369
last_received_ts = 138.980789
last_seq = 3886
current_ts = 138.998154
current_received_ts = 138.997528
current_seq = 3887
count = 388
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 138.981369/3886, current 138.998154/3887: elapsed=16795.0us expected=16885.8us +- 84.4us, error 0.5%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 138.981369/3886, current 138.998154/3887: elapsed=16795.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 138.998154
last_received_ts = 138.997528
last_seq = 3887
current_ts = 139.014954
current_received_ts = 139.014282
current_seq = 3888
count = 389
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 138.998154/3887, current 139.014954/3888: elapsed=16788.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 138.998154/3887, current 139.014954/3888: elapsed=16788.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.014954
last_received_ts = 139.014282
last_seq = 3888
current_ts = 139.031738
current_received_ts = 139.031113
current_seq = 3889
count = 390
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.014954/3888, current 139.031738/3889: elapsed=16795.0us expected=16885.8us +- 84.4us, error 0.5%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.014954/3888, current 139.031738/3889: elapsed=16795.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.031738
last_received_ts = 139.031113
last_seq = 3889
current_ts = 139.048538
current_received_ts = 139.047928
current_seq = 3890
count = 391
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.031738/3889, current 139.048538/3890: elapsed=16790.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.031738/3889, current 139.048538/3890: elapsed=16790.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.048538
last_received_ts = 139.047928
last_seq = 3890
current_ts = 139.065308
current_received_ts = 139.064636
current_seq = 3891
count = 392
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.048538/3890, current 139.065308/3891: elapsed=16774.0us expected=16885.8us +- 84.4us, error 0.7%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.048538/3890, current 139.065308/3891: elapsed=16774.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.065308
last_received_ts = 139.064636
last_seq = 3891
current_ts = 139.082092
current_received_ts = 139.081497
current_seq = 3892
count = 393
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.065308/3891, current 139.082092/3892: elapsed=16789.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.065308/3891, current 139.082092/3892: elapsed=16789.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.082092
last_received_ts = 139.081497
last_seq = 3892
current_ts = 139.098877
current_received_ts = 139.098267
current_seq = 3893
count = 394
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.082092/3892, current 139.098877/3893: elapsed=16785.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.082092/3892, current 139.098877/3893: elapsed=16785.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.098877
last_received_ts = 139.098267
last_seq = 3893
current_ts = 139.115677
current_received_ts = 139.115082
current_seq = 3894
count = 395
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.098877/3893, current 139.115677/3894: elapsed=16792.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.098877/3893, current 139.115677/3894: elapsed=16792.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.115677
last_received_ts = 139.115082
last_seq = 3894
current_ts = 139.132462
current_received_ts = 139.131882
current_seq = 3895
count = 396
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.115677/3894, current 139.132462/3895: elapsed=16792.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.115677/3894, current 139.132462/3895: elapsed=16792.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.132462
last_received_ts = 139.131882
last_seq = 3895
current_ts = 139.149246
current_received_ts = 139.148651
current_seq = 3896
count = 397
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.132462/3895, current 139.149246/3896: elapsed=16784.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.132462/3895, current 139.149246/3896: elapsed=16784.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.149246
last_received_ts = 139.148651
last_seq = 3896
current_ts = 139.166031
current_received_ts = 139.165436
current_seq = 3897
count = 398
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.149246/3896, current 139.166031/3897: elapsed=16788.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.149246/3896, current 139.166031/3897: elapsed=16788.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.166031
last_received_ts = 139.165436
last_seq = 3897
current_ts = 139.182831
current_received_ts = 139.182144
current_seq = 3898
count = 399
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.166031/3897, current 139.182831/3898: elapsed=16800.0us expected=16885.8us +- 84.4us, error 0.5%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.166031/3897, current 139.182831/3898: elapsed=16800.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.182831
last_received_ts = 139.182144
last_seq = 3898
current_ts = 139.199615
current_received_ts = 139.199036
current_seq = 3899
count = 400
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.182831/3898, current 139.199615/3899: elapsed=16777.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.182831/3898, current 139.199615/3899: elapsed=16777.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.199615
last_received_ts = 139.199036
last_seq = 3899
current_ts = 139.216415
current_received_ts = 139.215775
current_seq = 3900
count = 401
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.199615/3899, current 139.216415/3900: elapsed=16802.0us expected=16885.8us +- 84.4us, error 0.5%
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.216415
last_received_ts = 139.215775
last_seq = 3900
current_ts = 139.233185
current_received_ts = 139.232590
current_seq = 3901
count = 402
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.216415/3900, current 139.233185/3901: elapsed=16775.0us expected=16885.8us +- 84.4us, error 0.7%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.216415/3900, current 139.233185/3901: elapsed=16775.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.233185
last_received_ts = 139.232590
last_seq = 3901
current_ts = 139.249969
current_received_ts = 139.249374
current_seq = 3902
count = 403
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.233185/3901, current 139.249969/3902: elapsed=16782.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.233185/3901, current 139.249969/3902: elapsed=16782.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.249969
last_received_ts = 139.249374
last_seq = 3902
current_ts = 139.266785
current_received_ts = 139.266144
current_seq = 3903
count = 404
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.249969/3902, current 139.266785/3903: elapsed=16804.0us expected=16885.8us +- 84.4us, error 0.5%
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.266785
last_received_ts = 139.266144
last_seq = 3903
current_ts = 139.283554
current_received_ts = 139.282928
current_seq = 3904
count = 405
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.266785/3903, current 139.283554/3904: elapsed=16778.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.266785/3903, current 139.283554/3904: elapsed=16778.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.283554
last_received_ts = 139.282928
last_seq = 3904
current_ts = 139.300339
current_received_ts = 139.299728
current_seq = 3905
count = 406
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.283554/3904, current 139.300339/3905: elapsed=16790.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.283554/3904, current 139.300339/3905: elapsed=16790.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.300339
last_received_ts = 139.299728
last_seq = 3905
current_ts = 139.317139
current_received_ts = 139.316498
current_seq = 3906
count = 407
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.300339/3905, current 139.317139/3906: elapsed=16791.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.300339/3905, current 139.317139/3906: elapsed=16791.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.317139
last_received_ts = 139.316498
last_seq = 3906
current_ts = 139.333939
current_received_ts = 139.333282
current_seq = 3907
count = 408
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.317139/3906, current 139.333939/3907: elapsed=16798.0us expected=16885.8us +- 84.4us, error 0.5%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.317139/3906, current 139.333939/3907: elapsed=16798.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.333939
last_received_ts = 139.333282
last_seq = 3907
current_ts = 139.350708
current_received_ts = 139.350113
current_seq = 3908
count = 409
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.333939/3907, current 139.350708/3908: elapsed=16779.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.333939/3907, current 139.350708/3908: elapsed=16779.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.350708
last_received_ts = 139.350113
last_seq = 3908
current_ts = 139.367493
current_received_ts = 139.366913
current_seq = 3909
count = 410
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.350708/3908, current 139.367493/3909: elapsed=16781.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.350708/3908, current 139.367493/3909: elapsed=16781.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.367493
last_received_ts = 139.366913
last_seq = 3909
current_ts = 139.384277
current_received_ts = 139.383728
current_seq = 3910
count = 411
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.367493/3909, current 139.384277/3910: elapsed=16789.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.367493/3909, current 139.384277/3910: elapsed=16789.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.384277
last_received_ts = 139.383728
last_seq = 3910
current_ts = 139.401077
current_received_ts = 139.400436
current_seq = 3911
count = 412
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.384277/3910, current 139.401077/3911: elapsed=16797.0us expected=16885.8us +- 84.4us, error 0.5%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.384277/3910, current 139.401077/3911: elapsed=16797.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.401077
last_received_ts = 139.400436
last_seq = 3911
current_ts = 139.417862
current_received_ts = 139.417221
current_seq = 3912
count = 413
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.401077/3911, current 139.417862/3912: elapsed=16780.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.401077/3911, current 139.417862/3912: elapsed=16780.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.417862
last_received_ts = 139.417221
last_seq = 3912
current_ts = 139.434647
current_received_ts = 139.434067
current_seq = 3913
count = 414
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.417862/3912, current 139.434647/3913: elapsed=16786.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.417862/3912, current 139.434647/3913: elapsed=16786.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.434647
last_received_ts = 139.434067
last_seq = 3913
current_ts = 139.451431
current_received_ts = 139.450806
current_seq = 3914
count = 415
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.434647/3913, current 139.451431/3914: elapsed=16787.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.434647/3913, current 139.451431/3914: elapsed=16787.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.451431
last_received_ts = 139.450806
last_seq = 3914
current_ts = 139.468231
current_received_ts = 139.467606
current_seq = 3915
count = 416
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.451431/3914, current 139.468231/3915: elapsed=16801.0us expected=16885.8us +- 84.4us, error 0.5%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.451431/3914, current 139.468231/3915: elapsed=16801.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.468231
last_received_ts = 139.467606
last_seq = 3915
current_ts = 139.485016
current_received_ts = 139.484406
current_seq = 3916
count = 417
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.468231/3915, current 139.485016/3916: elapsed=16778.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.468231/3915, current 139.485016/3916: elapsed=16778.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.485016
last_received_ts = 139.484406
last_seq = 3916
current_ts = 139.501801
current_received_ts = 139.501114
current_seq = 3917
count = 418
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.485016/3916, current 139.501801/3917: elapsed=16786.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.485016/3916, current 139.501801/3917: elapsed=16786.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.501801
last_received_ts = 139.501114
last_seq = 3917
current_ts = 139.518600
current_received_ts = 139.517960
current_seq = 3918
count = 419
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.501801/3917, current 139.518600/3918: elapsed=16797.0us expected=16885.8us +- 84.4us, error 0.5%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.501801/3917, current 139.518600/3918: elapsed=16797.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.518600
last_received_ts = 139.517960
last_seq = 3918
current_ts = 139.535370
current_received_ts = 139.534775
current_seq = 3919
count = 420
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.518600/3918, current 139.535370/3919: elapsed=16777.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.518600/3918, current 139.535370/3919: elapsed=16777.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.535370
last_received_ts = 139.534775
last_seq = 3919
current_ts = 139.552170
current_received_ts = 139.551559
current_seq = 3920
count = 421
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.535370/3919, current 139.552170/3920: elapsed=16793.0us expected=16885.8us +- 84.4us, error 0.5%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.535370/3919, current 139.552170/3920: elapsed=16793.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.552170
last_received_ts = 139.551559
last_seq = 3920
current_ts = 139.568954
current_received_ts = 139.568359
current_seq = 3921
count = 422
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.552170/3920, current 139.568954/3921: elapsed=16788.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.552170/3920, current 139.568954/3921: elapsed=16788.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.568954
last_received_ts = 139.568359
last_seq = 3921
current_ts = 139.585739
current_received_ts = 139.585083
current_seq = 3922
count = 423
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.568954/3921, current 139.585739/3922: elapsed=16784.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.568954/3921, current 139.585739/3922: elapsed=16784.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.585739
last_received_ts = 139.585083
last_seq = 3922
current_ts = 139.602524
current_received_ts = 139.601929
current_seq = 3923
count = 424
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.585739/3922, current 139.602524/3923: elapsed=16791.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.585739/3922, current 139.602524/3923: elapsed=16791.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.602524
last_received_ts = 139.601929
last_seq = 3923
current_ts = 139.619324
current_received_ts = 139.618713
current_seq = 3924
count = 425
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.602524/3923, current 139.619324/3924: elapsed=16788.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.602524/3923, current 139.619324/3924: elapsed=16788.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.619324
last_received_ts = 139.618713
last_seq = 3924
current_ts = 139.636108
current_received_ts = 139.635468
current_seq = 3925
count = 426
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.619324/3924, current 139.636108/3925: elapsed=16796.0us expected=16885.8us +- 84.4us, error 0.5%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.619324/3924, current 139.636108/3925: elapsed=16796.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.636108
last_received_ts = 139.635468
last_seq = 3925
current_ts = 139.652893
current_received_ts = 139.652313
current_seq = 3926
count = 427
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.636108/3925, current 139.652893/3926: elapsed=16776.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.636108/3925, current 139.652893/3926: elapsed=16776.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.652893
last_received_ts = 139.652313
last_seq = 3926
current_ts = 139.669678
current_received_ts = 139.669083
current_seq = 3927
count = 428
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.652893/3926, current 139.669678/3927: elapsed=16792.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.652893/3926, current 139.669678/3927: elapsed=16792.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.669678
last_received_ts = 139.669083
last_seq = 3927
current_ts = 139.686462
current_received_ts = 139.685837
current_seq = 3928
count = 429
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.669678/3927, current 139.686462/3928: elapsed=16785.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.669678/3927, current 139.686462/3928: elapsed=16785.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.686462
last_received_ts = 139.685837
last_seq = 3928
current_ts = 139.703278
current_received_ts = 139.702652
current_seq = 3929
count = 430
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.686462/3928, current 139.703278/3929: elapsed=16807.0us expected=16885.8us +- 84.4us, error 0.5%
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.703278
last_received_ts = 139.702652
last_seq = 3929
current_ts = 139.720032
current_received_ts = 139.719391
current_seq = 3930
count = 431
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.703278/3929, current 139.720032/3930: elapsed=16765.0us expected=16885.8us +- 84.4us, error 0.7%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.703278/3929, current 139.720032/3930: elapsed=16765.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.720032
last_received_ts = 139.719391
last_seq = 3930
current_ts = 139.736832
current_received_ts = 139.736221
current_seq = 3931
count = 432
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.720032/3930, current 139.736832/3931: elapsed=16787.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.720032/3930, current 139.736832/3931: elapsed=16787.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.736832
last_received_ts = 139.736221
last_seq = 3931
current_ts = 139.753601
current_received_ts = 139.752914
current_seq = 3932
count = 433
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.736832/3931, current 139.753601/3932: elapsed=16772.0us expected=16885.8us +- 84.4us, error 0.7%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.736832/3931, current 139.753601/3932: elapsed=16772.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.753601
last_received_ts = 139.752914
last_seq = 3932
current_ts = 139.770386
current_received_ts = 139.769760
current_seq = 3933
count = 434
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.753601/3932, current 139.770386/3933: elapsed=16783.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.753601/3932, current 139.770386/3933: elapsed=16783.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.770386
last_received_ts = 139.769760
last_seq = 3933
current_ts = 139.787170
current_received_ts = 139.786530
current_seq = 3934
count = 435
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.770386/3933, current 139.787170/3934: elapsed=16793.0us expected=16885.8us +- 84.4us, error 0.5%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.770386/3933, current 139.787170/3934: elapsed=16793.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.787170
last_received_ts = 139.786530
last_seq = 3934
current_ts = 139.803940
current_received_ts = 139.803299
current_seq = 3935
count = 436
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.787170/3934, current 139.803940/3935: elapsed=16768.0us expected=16885.8us +- 84.4us, error 0.7%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.787170/3934, current 139.803940/3935: elapsed=16768.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.803940
last_received_ts = 139.803299
last_seq = 3935
current_ts = 139.820724
current_received_ts = 139.820068
current_seq = 3936
count = 437
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.803940/3935, current 139.820724/3936: elapsed=16781.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.803940/3935, current 139.820724/3936: elapsed=16781.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.820724
last_received_ts = 139.820068
last_seq = 3936
current_ts = 139.837494
current_received_ts = 139.836868
current_seq = 3937
count = 438
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.820724/3936, current 139.837494/3937: elapsed=16779.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.820724/3936, current 139.837494/3937: elapsed=16779.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.837494
last_received_ts = 139.836868
last_seq = 3937
current_ts = 139.854279
current_received_ts = 139.853638
current_seq = 3938
count = 439
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.837494/3937, current 139.854279/3938: elapsed=16777.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.837494/3937, current 139.854279/3938: elapsed=16777.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.854279
last_received_ts = 139.853638
last_seq = 3938
current_ts = 139.871063
current_received_ts = 139.870438
current_seq = 3939
count = 440
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.854279/3938, current 139.871063/3939: elapsed=16780.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.854279/3938, current 139.871063/3939: elapsed=16780.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.871063
last_received_ts = 139.870438
last_seq = 3939
current_ts = 139.887848
current_received_ts = 139.887161
current_seq = 3940
count = 441
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.871063/3939, current 139.887848/3940: elapsed=16791.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.871063/3939, current 139.887848/3940: elapsed=16791.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.887848
last_received_ts = 139.887161
last_seq = 3940
current_ts = 139.904633
current_received_ts = 139.903931
current_seq = 3941
count = 442
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.887848/3940, current 139.904633/3941: elapsed=16789.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.887848/3940, current 139.904633/3941: elapsed=16789.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.904633
last_received_ts = 139.903931
last_seq = 3941
current_ts = 139.921402
current_received_ts = 139.920807
current_seq = 3942
count = 443
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.904633/3941, current 139.921402/3942: elapsed=16764.0us expected=16885.8us +- 84.4us, error 0.7%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.904633/3941, current 139.921402/3942: elapsed=16764.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.921402
last_received_ts = 139.920807
last_seq = 3942
current_ts = 139.938187
current_received_ts = 139.937576
current_seq = 3943
count = 444
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.921402/3942, current 139.938187/3943: elapsed=16778.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.921402/3942, current 139.938187/3943: elapsed=16778.0us expected=16885.8us
(kms_flip:2680) DEBUG: name = flip
last_ts = 139.938187
last_received_ts = 139.937576
last_seq = 3943
current_ts = 139.954956
current_received_ts = 139.954376
current_seq = 3944
count = 445
seq_step = 1
(kms_flip:2680) DEBUG: flip ts/seq: last 139.938187/3943, current 139.954956/3944: elapsed=16782.0us expected=16885.8us +- 84.4us, error 0.6%
(kms_flip:2680) DEBUG: inconsistent flip ts/seq: last 139.938187/3943, current 139.954956/3944: elapsed=16782.0us expected=16885.8us
(kms_flip:2680) INFO: Event flip: expected 444, counted 446, passrate = 69.96%, encoder type 1
(kms_flip:2680) DEBUG: dropped frames, expected 444, counted 446, passrate = 69.96%, encoder type 1
(kms_flip:2680) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1709:
(kms_flip:2680) CRITICAL: Failed assertion: !retried
(kms_flip:2680) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:2680) igt_core-INFO: Stack trace:
(kms_flip:2680) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2680) igt_core-INFO:   #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
(kms_flip:2680) igt_core-INFO:   #2 ../tests/kms_flip.c:1889 run_test()
(kms_flip:2680) igt_core-INFO:   #3 ../tests/kms_flip.c:2158 __igt_unique____real_main2045()
(kms_flip:2680) igt_core-INFO:   #4 ../tests/kms_flip.c:2045 main()
(kms_flip:2680) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2680) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2680) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-VGA1: FAIL (18.731s)
Dmesg
<6> [120.463929] Console: switching to colour dummy device 80x25
<6> [120.464091] [IGT] kms_flip: executing
<7> [120.491291] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [120.492057] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [120.492467] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [120.495609] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:71:VGA-1]
<7> [120.495620] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:71:VGA-1] force=1
<7> [120.495853] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] ironlake hotplug adpa=0xf40000, result 0
<7> [120.496092] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT not detected via hotplug
<7> [120.496482] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [120.496697] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7> [120.497066] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [120.497281] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [120.497287] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7> [120.497509] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7> [120.498709] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [120.498714] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7> [120.498934] i915 0000:00:02.0: [drm:intel_crt_detect_ddc [i915]] CRT not detected via DDC:0x50 [no valid EDID found]
<7> [120.499223] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:71:VGA-1] disconnected
<7> [120.499661] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:82:DP-1]
<7> [120.499668] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:82:DP-1]
<7> [120.502446] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.505298] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.508179] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.511068] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.513910] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.516787] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.519720] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.522731] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.525827] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.528954] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.532071] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.535101] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.538188] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.541292] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.544339] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.547378] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.550405] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.553350] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.556363] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.559347] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.562428] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.565460] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.568502] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.571522] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.574591] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.577684] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.580766] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.583840] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.586934] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.590033] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.593075] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.596145] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.596642] i915 0000:00:02.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX D/DP D: Too many retries, giving up. First error: -110
<7> [120.599302] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.602361] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.605296] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.608325] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.611357] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.614469] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.617604] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.620734] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.623849] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.626946] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.630016] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.633066] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.636114] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.639135] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.642233] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.645301] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.648312] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.651330] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.654355] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.657459] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.660602] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.663705] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.666790] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.669803] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.672845] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.675833] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.678976] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.682123] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.685180] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.688215] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.691248] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.694341] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.694842] i915 0000:00:02.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX D/DP D: Too many retries, giving up. First error: -110
<7> [120.694881] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:82:DP-1] disconnected
<7> [120.695227] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:71:VGA-1]
<7> [120.695237] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:71:VGA-1] force=1
<7> [120.695626] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] ironlake hotplug adpa=0xf40000, result 0
<7> [120.695973] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT not detected via hotplug
<7> [120.696480] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [120.696885] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7> [120.697467] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [120.697794] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [120.697801] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7> [120.698096] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7> [120.699441] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [120.699449] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7> [120.699715] i915 0000:00:02.0: [drm:intel_crt_detect_ddc [i915]] CRT not detected via DDC:0x50 [no valid EDID found]
<7> [120.699983] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:71:VGA-1] disconnected
<7> [120.700407] i915 0000:00:02.0: [drm:status_store] [CONNECTOR:71:VGA-1] force updated from 0 to 2 or reprobing
<7> [120.700418] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:71:VGA-1]
<7> [120.700424] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:71:VGA-1] status updated from disconnected to connected
<7> [120.700441] i915 0000:00:02.0: [drm:drm_sysfs_hotplug_event] generating hotplug event
<7> [120.700521] i915 0000:00:02.0: [drm:drm_client_hotplug] fbdev: ret=0
<7> [120.700709] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [120.700989] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7> [120.701429] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [120.701682] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [120.701699] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7> [120.701958] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7> [120.703346] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [120.703364] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7> [120.703677] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:71:VGA-1] probed modes:
<7> [120.703684] 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> [120.703689] 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> [120.703695] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 56 36000 800 824 896 1024 600 601 603 625 0x40 0x5
<7> [120.703700] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "848x480": 60 33750 848 864 976 1088 480 486 494 517 0x40 0x5
<7> [120.703705] 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> [120.703748] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:71:VGA-1]
<7> [120.703920] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [120.704234] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7> [120.704695] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [120.704914] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [120.704926] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7> [120.705199] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7> [120.706526] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [120.706540] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7> [120.706830] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:71:VGA-1] probed modes:
<7> [120.706836] 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> [120.706842] 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> [120.706847] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 56 36000 800 824 896 1024 600 601 603 625 0x40 0x5
<7> [120.706852] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "848x480": 60 33750 848 864 976 1088 480 486 494 517 0x40 0x5
<7> [120.706857] 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> [120.706878] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:74:HDMI-A-1]
<7> [120.706886] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:74:HDMI-A-1]
<7> [120.732001] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:74:HDMI-A-1] HDMI: DVI dual 0, max TMDS clock 590000 kHz
<7> [120.732011] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:74:HDMI-A-1] HDMI sink does deep color 30.
<7> [120.732027] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:74:HDMI-A-1] HDMI sink does deep color 36.
<7> [120.732034] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:74:HDMI-A-1] HDMI sink does deep color 48.
<7> [120.732042] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:74:HDMI-A-1] Assigning HDMI sink color depth as 16 bpc.
<7> [120.732049] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:74:HDMI-A-1] HDMI sink does YCRCB444 in deep color.
<7> [120.732059] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:74:HDMI-A-1] ELD monitor Mi TV
<7> [120.732068] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:74:HDMI-A-1] HDMI: latency present 0 0, video latency 0 0, audio latency 0 0
<7> [120.732078] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:74:HDMI-A-1] ELD size 36, SAD count 3
<7> [120.732364] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK for addr: 0040 w(1)
<7> [120.732951] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK on first message, retry
<7> [120.733616] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK for addr: 0040 w(1)
<7> [120.734068] i915 0000:00:02.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [120.735515] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "2560x1600": 60 268500 2560 2608 2640 2720 1600 1603 1609 1646 0x40 0x5 (CLOCK_HIGH)
<7> [120.735526] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "2560x1440": 60 241500 2560 2608 2640 2720 1440 1443 1449 1481 0x40 0x5 (CLOCK_HIGH)
<7> [120.735534] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 30 297000 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [120.735542] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "4096x2160": 30 297000 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [120.735549] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080": 120 297000 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5 (CLOCK_HIGH)
<7> [120.735556] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 25 297000 3840 4896 4984 5280 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [120.735563] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 24 297000 3840 5116 5204 5500 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [120.735570] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "4096x2160": 24 297000 4096 5116 5204 5500 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [120.735578] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 50 594000 3840 4896 4984 5280 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [120.735585] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 60 594000 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [120.735592] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "4096x2160": 50 594000 4096 5064 5152 5280 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [120.735599] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "4096x2160": 60 594000 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [120.735606] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 30 296703 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [120.735613] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "4096x2160": 30 296703 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [120.735620] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080": 120 296703 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5 (CLOCK_HIGH)
<7> [120.735628] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 24 296703 3840 5116 5204 5500 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [120.735635] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "4096x2160": 24 296703 4096 5116 5204 5500 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [120.735642] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 60 593407 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [120.735649] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "4096x2160": 60 593407 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5 (CLOCK_HIGH)
<7> [120.735661] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:74:HDMI-A-1] probed modes:
<7> [120.735667] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [120.735672] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2880x1800": 30 167000 2880 2910 2920 3030 1800 1820 1828 1836 0x40 0x5
<7> [120.735677] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1200": 60 154000 1920 1968 2000 2080 1200 1203 1209 1235 0x40 0x9
<7> [120.735681] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [120.735686] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 60 148352 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [120.735691] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080i": 60 74250 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x15
<7> [120.735696] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080i": 60 74250 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x20015
<7> [120.735700] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080i": 60 74176 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x15
<7> [120.735705] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080i": 60 74176 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x20015
<7> [120.735710] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 50 148500 1920 2448 2492 2640 1080 1084 1089 1125 0x40 0x5
<7> [120.735715] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080i": 50 74250 1920 2448 2492 2640 1080 1084 1094 1125 0x40 0x15
<7> [120.735719] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080i": 50 74250 1920 2448 2492 2640 1080 1084 1094 1125 0x40 0x20015
<7> [120.735724] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 30 74250 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [120.735729] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 30 74176 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [120.735734] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 25 74250 1920 2448 2492 2640 1080 1084 1089 1125 0x40 0x5
<7> [120.735738] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 24 74250 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x5
<7> [120.735743] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 24 74250 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x1c005
<7> [120.735748] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 24 74250 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x4005
<7> [120.735752] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 24 74176 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x5
<7> [120.735770] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 24 74176 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x1c005
<7> [120.735775] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 24 74176 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x4005
<7> [120.735780] 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> [120.735785] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1680x1050": 60 119000 1680 1728 1760 1840 1050 1053 1059 1080 0x40 0x9
<7> [120.735790] 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> [120.735794] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x1024": 60 108000 1280 1328 1440 1688 1024 1025 1028 1066 0x40 0x5
<7> [120.735799] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1440x900": 60 88750 1440 1488 1520 1600 900 903 909 926 0x40 0x9
<7> [120.735804] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1366x768": 60 85500 1366 1436 1579 1792 768 771 774 798 0x40 0x5
<7> [120.735809] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x800": 60 71000 1280 1328 1360 1440 800 803 809 823 0x40 0x9
<7> [120.735814] 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> [120.735819] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74250 1280 1390 1430 1650 720 725 730 750 0x40 0x1c005
<7> [120.735823] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74250 1280 1390 1430 1650 720 725 730 750 0x40 0x4005
<7> [120.735828] 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> [120.735833] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74176 1280 1390 1430 1650 720 725 730 750 0x40 0x1c005
<7> [120.735838] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74176 1280 1390 1430 1650 720 725 730 750 0x40 0x4005
<7> [120.735842] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 50 74250 1280 1720 1760 1980 720 725 730 750 0x40 0x5
<7> [120.735847] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 50 74250 1280 1720 1760 1980 720 725 730 750 0x40 0x1c005
<7> [120.735852] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 50 74250 1280 1720 1760 1980 720 725 730 750 0x40 0x4005
<7> [120.735856] 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> [120.735861] 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> [120.735866] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "720x576": 50 27000 720 732 796 864 576 581 586 625 0x40 0xa
<7> [120.735870] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "720x576i": 50 13500 720 732 795 864 576 580 586 625 0x40 0x101a
<7> [120.735875] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "720x480": 60 27027 720 736 798 858 480 489 495 525 0x40 0xa
<7> [120.735880] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "720x480": 60 27000 720 736 798 858 480 489 495 525 0x40 0xa
<7> [120.735884] 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> [120.735889] 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> [120.735939] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:82:DP-1]
<7> [120.735945] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:82:DP-1]
<7> [120.738712] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.741554] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.744411] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.747290] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.750160] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.753010] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.755973] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.758826] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.761737] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.764584] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.767440] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.770290] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.773155] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.776078] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.778953] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.781807] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.784721] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.787587] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.790435] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.793317] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.796188] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.799072] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.801951] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.804833] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.807952] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.811011] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.814099] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.817229] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.820374] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.823425] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.826524] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.829621] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.830116] i915 0000:00:02.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX D/DP D: Too many retries, giving up. First error: -110
<7> [120.832766] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.835795] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.838772] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.841858] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.844970] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.848081] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.851154] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.854167] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.857202] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.860361] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.863472] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.866529] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.869563] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.872640] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.875768] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.878886] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.881849] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.884934] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.888005] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.891132] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.894311] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.897390] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.900457] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.903576] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.906670] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.909740] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.912826] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.915997] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.919159] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.922236] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.925336] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.928477] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [120.928999] i915 0000:00:02.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX D/DP D: Too many retries, giving up. First error: -110
<7> [120.929048] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:82:DP-1] disconnected
<6> [120.936644] [IGT] kms_flip: starting subtest plain-flip-ts-check-interruptible
<6> [120.937922] [IGT] kms_flip: starting dynamic subtest A-VGA1
<7> [120.938537] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:86]
<7> [120.938691] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:90]
<7> [120.970187] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:51:pipe A]
<7> [120.970307] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [120.970570] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [120.970791] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [120.971062] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in has_pch_encoder (expected yes, found no)
<7> [120.971308] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in fdi_lanes (expected 3, found 0)
<7> [120.971566] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in fdi_m_n (expected tu 64 data 6934582/8388608 link 288940/524288, found tu 0, data 0/0 link 0/0)
<7> [120.971834] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [120.972156] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [120.972416] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [120.972649] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [120.972969] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [120.973239] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [120.973489] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [120.973720] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [120.974013] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [120.974318] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [120.974556] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [120.974837] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [120.975092] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [120.975317] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [120.975552] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [120.975851] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [120.976097] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [120.976341] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [120.976578] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [120.976887] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [120.977125] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [120.977361] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [120.977654] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [120.977897] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [120.978131] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [120.978383] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [120.978656] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [120.978927] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [120.979161] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [120.979396] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [120.979701] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [120.979948] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [120.980191] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [120.980436] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148800, found 0)
<7> [120.980731] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148800, found 0)
<7> [120.980969] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in port_clock (expected 223200, found 0)
<7> [120.981205] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [120.981509] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [120.981770] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in avi infoframe
<7> [120.982005] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [120.982256] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [120.982259] i915 0000:00:02.0: colorspace: RGB
<7> [120.982261] i915 0000:00:02.0: scan mode: Underscan
<7> [120.982263] i915 0000:00:02.0: colorimetry: No Data
<7> [120.982265] i915 0000:00:02.0: picture aspect: 16:9
<7> [120.982267] i915 0000:00:02.0: active aspect: Same as Picture
<7> [120.982269] i915 0000:00:02.0: itc: No Data
<7> [120.982271] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [120.982273] i915 0000:00:02.0: quantization range: Default
<7> [120.982275] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [120.982277] i915 0000:00:02.0: video code: 16
<7> [120.982279] i915 0000:00:02.0: ycc quantization range: Full
<7> [120.982281] i915 0000:00:02.0: hdmi content type: Graphics
<7> [120.982283] i915 0000:00:02.0: pixel repeat: 0
<7> [120.982285] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [120.982287] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [120.982588] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in spd infoframe
<7> [120.982826] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [120.983071] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [120.983073] i915 0000:00:02.0: vendor: Intel
<7> [120.983075] i915 0000:00:02.0: product: Integrated gfx
<7> [120.983078] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [120.983080] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [120.983310] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hdmi infoframe
<7> [120.983596] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [120.983851] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [120.983854] i915 0000:00:02.0: empty frame
<7> [120.983856] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [120.984086] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:51:pipe A] fastset requirement not met, forcing full modeset
<7> [120.984358] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:51:pipe A] releasing PCH DPLL A
<7> [120.984649] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:51:pipe A] min cdclk: 165334 kHz -> 0 kHz
<7> [120.984953] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:51:pipe A] enable: no [modeset]
<7> [120.985179] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:33:primary A] fb: [NOFB], visible: no
<7> [120.985552] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:74:HDMI-A-1][ENCODER:73:HDMI D] Disable audio codec on [CRTC:51:pipe A]
<7> [121.005867] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [121.023018] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found
<7> [121.023489] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found
<7> [121.023989] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found
<7> [121.035487] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PCH DPLL A (active 0x1, on? 1) for [CRTC:51:pipe A]
<7> [121.035961] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PCH DPLL A
<7> [121.036676] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:72:CRT]
<7> [121.037147] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:73:HDMI D]
<7> [121.037646] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:81:DP D]
<7> [121.038155] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:74:HDMI-A-1]
<7> [121.038752] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:51:pipe A]
<7> [121.039359] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:70:pipe B]
<7> [121.039516] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:51:pipe A]
<7> [121.039544] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:71:VGA-1]
<7> [121.039639] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:71:VGA-1] Limiting display bpp to 24 (EDID bpp 0, max requested bpp 0, max platform bpp 36)
<7> [121.039906] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:51:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [121.040195] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe A, lanes 1
<7> [121.040442] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [121.040678] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [121.040945] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [121.041198] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in has_pch_encoder (expected no, found yes)
<7> [121.041424] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in fdi_lanes (expected 0, found 1)
<7> [121.041659] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in fdi_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6039797/8388608 link 125829/524288)
<7> [121.041958] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000002)
<7> [121.042248] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [121.042472] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1024)
<7> [121.042721] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 1344)
<7> [121.043078] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1024)
<7> [121.043401] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 1344)
<7> [121.043634] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1048)
<7> [121.043900] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 1184)
<7> [121.044146] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 768)
<7> [121.044383] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 768)
<7> [121.044653] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 771)
<7> [121.044919] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 777)
<7> [121.045173] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 806)
<7> [121.045421] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 806)
<7> [121.045674] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1024)
<7> [121.045966] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 1344)
<7> [121.046219] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1024)
<7> [121.046472] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 1344)
<7> [121.046730] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1048)
<7> [121.046999] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 1184)
<7> [121.047245] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 768)
<7> [121.047509] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 768)
<7> [121.047759] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 771)
<7> [121.048001] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 777)
<7> [121.048233] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 806)
<7> [121.048481] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 806)
<7> [121.048757] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [121.048998] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [121.049238] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [121.049480] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in dpll_hw_state
<7> [121.049777] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [121.050009] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0xc4010001, dpll_md: 0x0, fp0: 0x30f06, fp1: 0x30f06
<7> [121.050251] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [121.050498] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [121.050774] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [121.051017] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 64800)
<7> [121.051253] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 64800)
<7> [121.051543] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in port_clock (expected 0, found 64800)
<7> [121.051819] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:51:pipe A] fastset requirement not met, forcing full modeset
<7> [121.052125] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:51:pipe A] min cdclk: 0 kHz -> 72000 kHz
<7> [121.052401] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:51:pipe A] allocated PCH DPLL A
<7> [121.052677] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:51:pipe A] reserving PCH DPLL A
<7> [121.052926] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:51:pipe A] enable: yes [modeset]
<7> [121.053158] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB
<7> [121.053406] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [121.053636] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [121.053865] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [121.054093] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [121.054325] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [121.054564] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] fdi: lanes: 1; data_m: 6039797, data_n: 8388608, link_m: 125829, link_n: 524288, tu: 64
<7> [121.054796] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [121.055026] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1
<7> [121.055255] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [121.055492] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0
<7> [121.055723] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 0, vmax vblank: 0, vmin vtotal: 0, vmax vtotal: 0
<7> [121.055952] 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> [121.056182] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [121.056439] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [121.056451] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [121.056678] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=64800, hd=1024 hb=1024-1344 hs=1048-1184 ht=1344, vd=768 vb=768-806 vs=771-777 vt=806, flags=0xa
<7> [121.056903] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1024x768": 60 64800 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [121.057127] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=64800, hd=1024 hb=1024-1344 hs=1048-1184 ht=1344, vd=768 vb=768-806 vs=771-777 vt=806, flags=0xa
<7> [121.057401] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 64800, pipe src: 1024x768+0+0, pixel rate 64800, min cdclk 72000
<7> [121.057641] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0
<7> [121.057864] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [121.058087] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [121.058343] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [121.058584] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [121.058807] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [121.059029] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [121.059296] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [121.059526] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [121.059755] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [121.059984] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [121.060229] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:33:primary A] fb: [FB:86] 1024x768 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [121.060461] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [121.060690] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0
<7> [121.062429] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [121.062694] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:72:CRT]
<7> [121.062919] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:73:HDMI D]
<7> [121.063159] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:81:DP D]
<7> [121.063957] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [121.065059] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100
<7> [121.065332] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done.
<7> [121.066271] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x200
<7> [121.066540] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done.
<7> [121.066785] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train done.
<7> [121.067104] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PCH DPLL A (active 0x1, on? 0) for [CRTC:51:pipe A]
<7> [121.067383] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PCH DPLL A
<7> [121.119389] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:71:VGA-1]
<7> [121.120043] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:51:pipe A]
<7> [131.708892] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:51:pipe A]
<7> [131.709086] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [131.710152] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [131.710691] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [131.711245] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in has_pch_encoder (expected yes, found no)
<7> [131.711619] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in fdi_lanes (expected 1, found 0)
<7> [131.711890] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in fdi_m_n (expected tu 64 data 6039797/8388608 link 125829/524288, found tu 0, data 0/0 link 0/0)
<7> [131.712162] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in output_types (expected 0x00000002, found 0x00000000)
<7> [131.712442] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [131.712713] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1024, found 0)
<7> [131.712984] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 1344, found 0)
<7> [131.713299] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1024, found 0)
<7> [131.713570] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 1344, found 0)
<7> [131.713839] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1048, found 0)
<7> [131.714109] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 1184, found 0)
<7> [131.714371] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 768, found 0)
<7> [131.714603] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 768, found 0)
<7> [131.714834] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 771, found 0)
<7> [131.715065] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 777, found 0)
<7> [131.715324] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 806, found 0)
<7> [131.715556] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 806, found 0)
<7> [131.715787] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1024, found 0)
<7> [131.716017] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 1344, found 0)
<7> [131.716255] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1024, found 0)
<7> [131.716486] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 1344, found 0)
<7> [131.716716] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1048, found 0)
<7> [131.716950] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 1184, found 0)
<7> [131.717236] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 768, found 0)
<7> [131.717473] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 768, found 0)
<7> [131.717703] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 771, found 0)
<7> [131.717933] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 777, found 0)
<7> [131.718172] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 806, found 0)
<7> [131.718403] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 806, found 0)
<7> [131.718633] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [131.718863] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [131.719094] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [131.719333] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [131.719564] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 64800, found 0)
<7> [131.719795] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 64800, found 0)
<7> [131.720025] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in port_clock (expected 64800, found 0)
<7> [131.720277] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:51:pipe A] fastset requirement not met, forcing full modeset
<7> [131.720508] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:51:pipe A] releasing PCH DPLL A
<7> [131.720789] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:51:pipe A] min cdclk: 72000 kHz -> 0 kHz
<7> [131.721030] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:51:pipe A] enable: no [modeset]
<7> [131.721283] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:33:primary A] fb: [NOFB], visible: no
<7> [131.721592] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [131.735968] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found
<7> [131.736440] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found
<7> [131.736897] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found
<7> [131.753497] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PCH DPLL A (active 0x1, on? 1) for [CRTC:51:pipe A]
<7> [131.754025] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PCH DPLL A
<7> [131.754732] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:72:CRT]
<7> [131.755230] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:73:HDMI D]
<7> [131.755696] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:81:DP D]
<7> [131.756200] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:71:VGA-1]
<7> [131.756921] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:51:pipe A]
<7> [131.757454] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:70:pipe B]
<7> [131.757665] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:51:pipe A]
<7> [131.757702] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:71:VGA-1]
<7> [131.757854] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:71:VGA-1] Limiting display bpp to 24 (EDID bpp 0, max requested bpp 0, max platform bpp 36)
<7> [131.758223] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:51:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [131.758568] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe A, lanes 1
<7> [131.758942] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [131.759295] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [131.759648] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [131.760021] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in has_pch_encoder (expected no, found yes)
<7> [131.760375] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in fdi_lanes (expected 0, found 1)
<7> [131.760725] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in fdi_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6039797/8388608 link 125829/524288)
<7> [131.761093] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000002)
<7> [131.761446] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [131.761838] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1024)
<7> [131.762206] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 1344)
<7> [131.762551] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1024)
<7> [131.762927] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 1344)
<7> [131.763291] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1048)
<7> [131.763644] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 1184)
<7> [131.764013] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 768)
<7> [131.764377] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 768)
<7> [131.764779] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 771)
<7> [131.765136] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 777)
<7> [131.765480] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 806)
<7> [131.765936] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 806)
<7> [131.766308] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1024)
<7> [131.766707] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 1344)
<7> [131.767086] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1024)
<7> [131.767440] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 1344)
<7> [131.767822] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1048)
<7> [131.768180] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 1184)
<7> [131.768537] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 768)
<7> [131.768913] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 768)
<7> [131.769269] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 771)
<7> [131.769639] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 777)
<7> [131.769997] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 806)
<7> [131.770350] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 806)
<7> [131.770725] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [131.771087] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [131.771462] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [131.771839] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [131.772197] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 64800)
<7> [131.772557] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 64800)
<7> [131.772923] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in port_clock (expected 0, found 64800)
<7> [131.773281] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:51:pipe A] fastset requirement not met, forcing full modeset
<7> [131.773717] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:51:pipe A] min cdclk: 0 kHz -> 72000 kHz
<7> [131.774053] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:51:pipe A] allocated PCH DPLL A
<7> [131.774396] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:51:pipe A] reserving PCH DPLL A
<7> [131.774786] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:51:pipe A] enable: yes [modeset]
<7> [131.774802] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [131.775143] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB
<7> [131.775495] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [131.775867] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [131.776220] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [131.776587] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [131.776922] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [131.777272] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] fdi: lanes: 1; data_m: 6039797, data_n: 8388608, link_m: 125829, link_n: 524288, tu: 64
<7> [131.777643] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [131.777997] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1
<7> [131.778349] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [131.778721] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0
<7> [131.779078] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 0, vmax vblank: 0, vmin vtotal: 0, vmax vtotal: 0
<7> [131.779429] 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> [131.779799] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [131.780154] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [131.780523] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=64800, hd=1024 hb=1024-1344 hs=1048-1184 ht=1344, vd=768 vb=768-806 vs=771-777 vt=806, flags=0xa
<7> [131.780881] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1024x768": 60 64800 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [131.781244] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=64800, hd=1024 hb=1024-1344 hs=1048-1184 ht=1344, vd=768 vb=768-806 vs=771-777 vt=806, flags=0xa
<7> [131.781619] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 64800, pipe src: 1024x768+0+0, pixel rate 64800, min cdclk 72000
<7> [131.781996] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0
<7> [131.782340] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [131.782755] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [131.783105] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [131.783471] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [131.783826] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [131.784178] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [131.784547] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [131.784902] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [131.785256] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [131.785625] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [131.785978] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:33:primary A] fb: [FB:86] 1024x768 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [131.786334] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [131.786707] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0
<7> [131.787150] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [131.787449] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:72:CRT]
<7> [131.787712] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:73:HDMI D]
<7> [131.787931] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:81:DP D]
<7> [131.788755] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [131.789836] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100
<7> [131.790075] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done.
<7> [131.790963] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x200
<7> [131.791179] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done.
<7> [131.791450] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train done.
<7> [131.791807] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PCH DPLL A (active 0x1, on? 0) for [CRTC:51:pipe A]
<7> [131.792050] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PCH DPLL A
<7> [131.844060] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:71:VGA-1]
<7> [131.844603] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:51:pipe A]
<6> [139.669233] [IGT] kms_flip: finished subtest A-VGA1, FAIL
Created at 2026-01-12 19:27:18