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.68 seconds
Hostname 
shard-snb4
Igt-Version 
IGT-Version: 2.3-ge61a8b4ea (x86_64) (Linux: 6.19.0-rc5-CI_DRM_17808-g630dc29de423+ x86_64)
Out 
Using IGT_SRANDOM=1768246429 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 17528.4us +- 6.889us accuracy 0.12%
vblank interval differs from modeline! expected 16665.6us, measured 17528us +- 6.889us, difference 862.8us (125.2 sigma)
Event flip: expected 428, counted 436, passrate = 3.44%, encoder type 1
Expected frametime: 16666us; measured 16910.2us +- 8.727us accuracy 0.15%
vblank interval differs from modeline! expected 16665.6us, measured 16910us +- 8.727us, difference 244.6us (28.0 sigma)
Event flip: expected 444, counted 446, passrate = 57.85%, 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.678s)
Err 
Starting subtest: plain-flip-ts-check-interruptible
Starting dynamic subtest: A-VGA1
(kms_flip:2717) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1709:
(kms_flip:2717) CRITICAL: Failed assertion: !retried
(kms_flip:2717) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest A-VGA1 failed.
**** DEBUG ****
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.028030/4019, current 188.044846/4020: elapsed=16810.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.044846
last_received_ts = 188.044205
last_seq = 4020
current_ts = 188.061646
current_received_ts = 188.061066
current_seq = 4021
count = 366
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.044846/4020, current 188.061646/4021: elapsed=16797.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.044846/4020, current 188.061646/4021: elapsed=16797.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.061646
last_received_ts = 188.061066
last_seq = 4021
current_ts = 188.078445
current_received_ts = 188.077881
current_seq = 4022
count = 367
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.061646/4021, current 188.078445/4022: elapsed=16805.0us expected=16910.2us +- 84.6us, error 0.6%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.061646/4021, current 188.078445/4022: elapsed=16805.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.078445
last_received_ts = 188.077881
last_seq = 4022
current_ts = 188.095261
current_received_ts = 188.094666
current_seq = 4023
count = 368
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.078445/4022, current 188.095261/4023: elapsed=16808.0us expected=16910.2us +- 84.6us, error 0.6%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.078445/4022, current 188.095261/4023: elapsed=16808.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.095261
last_received_ts = 188.094666
last_seq = 4023
current_ts = 188.112076
current_received_ts = 188.111420
current_seq = 4024
count = 369
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.095261/4023, current 188.112076/4024: elapsed=16822.0us expected=16910.2us +- 84.6us, error 0.5%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.095261/4023, current 188.112076/4024: elapsed=16822.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.112076
last_received_ts = 188.111420
last_seq = 4024
current_ts = 188.128876
current_received_ts = 188.128235
current_seq = 4025
count = 370
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.112076/4024, current 188.128876/4025: elapsed=16797.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.112076/4024, current 188.128876/4025: elapsed=16797.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.128876
last_received_ts = 188.128235
last_seq = 4025
current_ts = 188.145676
current_received_ts = 188.145142
current_seq = 4026
count = 371
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.128876/4025, current 188.145676/4026: elapsed=16807.0us expected=16910.2us +- 84.6us, error 0.6%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.128876/4025, current 188.145676/4026: elapsed=16807.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.145676
last_received_ts = 188.145142
last_seq = 4026
current_ts = 188.162476
current_received_ts = 188.161880
current_seq = 4027
count = 372
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.145676/4026, current 188.162476/4027: elapsed=16789.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.145676/4026, current 188.162476/4027: elapsed=16789.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.162476
last_received_ts = 188.161880
last_seq = 4027
current_ts = 188.179276
current_received_ts = 188.178665
current_seq = 4028
count = 373
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.162476/4027, current 188.179276/4028: elapsed=16798.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.162476/4027, current 188.179276/4028: elapsed=16798.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.179276
last_received_ts = 188.178665
last_seq = 4028
current_ts = 188.196075
current_received_ts = 188.195511
current_seq = 4029
count = 374
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.179276/4028, current 188.196075/4029: elapsed=16802.0us expected=16910.2us +- 84.6us, error 0.6%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.179276/4028, current 188.196075/4029: elapsed=16802.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.196075
last_received_ts = 188.195511
last_seq = 4029
current_ts = 188.212860
current_received_ts = 188.212219
current_seq = 4030
count = 375
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.196075/4029, current 188.212860/4030: elapsed=16796.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.196075/4029, current 188.212860/4030: elapsed=16796.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.212860
last_received_ts = 188.212219
last_seq = 4030
current_ts = 188.229660
current_received_ts = 188.229034
current_seq = 4031
count = 376
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.212860/4030, current 188.229660/4031: elapsed=16798.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.212860/4030, current 188.229660/4031: elapsed=16798.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.229660
last_received_ts = 188.229034
last_seq = 4031
current_ts = 188.246460
current_received_ts = 188.245850
current_seq = 4032
count = 377
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.229660/4031, current 188.246460/4032: elapsed=16795.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.229660/4031, current 188.246460/4032: elapsed=16795.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.246460
last_received_ts = 188.245850
last_seq = 4032
current_ts = 188.263275
current_received_ts = 188.262695
current_seq = 4033
count = 378
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.246460/4032, current 188.263275/4033: elapsed=16808.0us expected=16910.2us +- 84.6us, error 0.6%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.246460/4032, current 188.263275/4033: elapsed=16808.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.263275
last_received_ts = 188.262695
last_seq = 4033
current_ts = 188.280060
current_received_ts = 188.279449
current_seq = 4034
count = 379
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.263275/4033, current 188.280060/4034: elapsed=16785.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.263275/4033, current 188.280060/4034: elapsed=16785.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.280060
last_received_ts = 188.279449
last_seq = 4034
current_ts = 188.296860
current_received_ts = 188.296249
current_seq = 4035
count = 380
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.280060/4034, current 188.296860/4035: elapsed=16812.0us expected=16910.2us +- 84.6us, error 0.6%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.280060/4034, current 188.296860/4035: elapsed=16812.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.296860
last_received_ts = 188.296249
last_seq = 4035
current_ts = 188.313660
current_received_ts = 188.313080
current_seq = 4036
count = 381
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.296860/4035, current 188.313660/4036: elapsed=16792.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.296860/4035, current 188.313660/4036: elapsed=16792.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.313660
last_received_ts = 188.313080
last_seq = 4036
current_ts = 188.330444
current_received_ts = 188.329849
current_seq = 4037
count = 382
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.313660/4036, current 188.330444/4037: elapsed=16791.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.313660/4036, current 188.330444/4037: elapsed=16791.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.330444
last_received_ts = 188.329849
last_seq = 4037
current_ts = 188.347244
current_received_ts = 188.346664
current_seq = 4038
count = 383
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.330444/4037, current 188.347244/4038: elapsed=16800.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.330444/4037, current 188.347244/4038: elapsed=16800.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.347244
last_received_ts = 188.346664
last_seq = 4038
current_ts = 188.364044
current_received_ts = 188.363403
current_seq = 4039
count = 384
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.347244/4038, current 188.364044/4039: elapsed=16793.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.347244/4038, current 188.364044/4039: elapsed=16793.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.364044
last_received_ts = 188.363403
last_seq = 4039
current_ts = 188.380844
current_received_ts = 188.380219
current_seq = 4040
count = 385
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.364044/4039, current 188.380844/4040: elapsed=16809.0us expected=16910.2us +- 84.6us, error 0.6%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.364044/4039, current 188.380844/4040: elapsed=16809.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.380844
last_received_ts = 188.380219
last_seq = 4040
current_ts = 188.397644
current_received_ts = 188.397141
current_seq = 4041
count = 386
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.380844/4040, current 188.397644/4041: elapsed=16792.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.380844/4040, current 188.397644/4041: elapsed=16792.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.397644
last_received_ts = 188.397141
last_seq = 4041
current_ts = 188.414444
current_received_ts = 188.413834
current_seq = 4042
count = 387
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.397644/4041, current 188.414444/4042: elapsed=16798.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.397644/4041, current 188.414444/4042: elapsed=16798.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.414444
last_received_ts = 188.413834
last_seq = 4042
current_ts = 188.431244
current_received_ts = 188.430588
current_seq = 4043
count = 388
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.414444/4042, current 188.431244/4043: elapsed=16809.0us expected=16910.2us +- 84.6us, error 0.6%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.414444/4042, current 188.431244/4043: elapsed=16809.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.431244
last_received_ts = 188.430588
last_seq = 4043
current_ts = 188.448044
current_received_ts = 188.447449
current_seq = 4044
count = 389
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.431244/4043, current 188.448044/4044: elapsed=16796.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.431244/4043, current 188.448044/4044: elapsed=16796.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.448044
last_received_ts = 188.447449
last_seq = 4044
current_ts = 188.464844
current_received_ts = 188.464127
current_seq = 4045
count = 390
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.448044/4044, current 188.464844/4045: elapsed=16801.0us expected=16910.2us +- 84.6us, error 0.6%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.448044/4044, current 188.464844/4045: elapsed=16801.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.464844
last_received_ts = 188.464127
last_seq = 4045
current_ts = 188.481628
current_received_ts = 188.481049
current_seq = 4046
count = 391
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.464844/4045, current 188.481628/4046: elapsed=16788.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.464844/4045, current 188.481628/4046: elapsed=16788.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.481628
last_received_ts = 188.481049
last_seq = 4046
current_ts = 188.498444
current_received_ts = 188.497818
current_seq = 4047
count = 392
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.481628/4046, current 188.498444/4047: elapsed=16809.0us expected=16910.2us +- 84.6us, error 0.6%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.481628/4046, current 188.498444/4047: elapsed=16809.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.498444
last_received_ts = 188.497818
last_seq = 4047
current_ts = 188.515228
current_received_ts = 188.514603
current_seq = 4048
count = 393
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.498444/4047, current 188.515228/4048: elapsed=16788.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.498444/4047, current 188.515228/4048: elapsed=16788.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.515228
last_received_ts = 188.514603
last_seq = 4048
current_ts = 188.532028
current_received_ts = 188.531403
current_seq = 4049
count = 394
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.515228/4048, current 188.532028/4049: elapsed=16791.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.515228/4048, current 188.532028/4049: elapsed=16791.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.532028
last_received_ts = 188.531403
last_seq = 4049
current_ts = 188.548828
current_received_ts = 188.548172
current_seq = 4050
count = 395
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.532028/4049, current 188.548828/4050: elapsed=16802.0us expected=16910.2us +- 84.6us, error 0.6%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.532028/4049, current 188.548828/4050: elapsed=16802.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.548828
last_received_ts = 188.548172
last_seq = 4050
current_ts = 188.565613
current_received_ts = 188.565033
current_seq = 4051
count = 396
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.548828/4050, current 188.565613/4051: elapsed=16796.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.548828/4050, current 188.565613/4051: elapsed=16796.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.565613
last_received_ts = 188.565033
last_seq = 4051
current_ts = 188.582413
current_received_ts = 188.581818
current_seq = 4052
count = 397
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.565613/4051, current 188.582413/4052: elapsed=16798.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.565613/4051, current 188.582413/4052: elapsed=16798.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.582413
last_received_ts = 188.581818
last_seq = 4052
current_ts = 188.599213
current_received_ts = 188.598618
current_seq = 4053
count = 398
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.582413/4052, current 188.599213/4053: elapsed=16797.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.582413/4052, current 188.599213/4053: elapsed=16797.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.599213
last_received_ts = 188.598618
last_seq = 4053
current_ts = 188.616013
current_received_ts = 188.615402
current_seq = 4054
count = 399
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.599213/4053, current 188.616013/4054: elapsed=16797.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.599213/4053, current 188.616013/4054: elapsed=16797.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.616013
last_received_ts = 188.615402
last_seq = 4054
current_ts = 188.632828
current_received_ts = 188.632156
current_seq = 4055
count = 400
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.616013/4054, current 188.632828/4055: elapsed=16812.0us expected=16910.2us +- 84.6us, error 0.6%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.616013/4054, current 188.632828/4055: elapsed=16812.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.632828
last_received_ts = 188.632156
last_seq = 4055
current_ts = 188.649612
current_received_ts = 188.648941
current_seq = 4056
count = 401
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.632828/4055, current 188.649612/4056: elapsed=16794.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.632828/4055, current 188.649612/4056: elapsed=16794.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.649612
last_received_ts = 188.648941
last_seq = 4056
current_ts = 188.666412
current_received_ts = 188.665756
current_seq = 4057
count = 402
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.649612/4056, current 188.666412/4057: elapsed=16789.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.649612/4056, current 188.666412/4057: elapsed=16789.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.666412
last_received_ts = 188.665756
last_seq = 4057
current_ts = 188.683212
current_received_ts = 188.682571
current_seq = 4058
count = 403
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.666412/4057, current 188.683212/4058: elapsed=16802.0us expected=16910.2us +- 84.6us, error 0.6%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.666412/4057, current 188.683212/4058: elapsed=16802.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.683212
last_received_ts = 188.682571
last_seq = 4058
current_ts = 188.700012
current_received_ts = 188.699341
current_seq = 4059
count = 404
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.683212/4058, current 188.700012/4059: elapsed=16808.0us expected=16910.2us +- 84.6us, error 0.6%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.683212/4058, current 188.700012/4059: elapsed=16808.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.700012
last_received_ts = 188.699341
last_seq = 4059
current_ts = 188.716797
current_received_ts = 188.716141
current_seq = 4060
count = 405
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.700012/4059, current 188.716797/4060: elapsed=16787.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.700012/4059, current 188.716797/4060: elapsed=16787.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.716797
last_received_ts = 188.716141
last_seq = 4060
current_ts = 188.733597
current_received_ts = 188.732925
current_seq = 4061
count = 406
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.716797/4060, current 188.733597/4061: elapsed=16796.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.716797/4060, current 188.733597/4061: elapsed=16796.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.733597
last_received_ts = 188.732925
last_seq = 4061
current_ts = 188.750412
current_received_ts = 188.749786
current_seq = 4062
count = 407
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.733597/4061, current 188.750412/4062: elapsed=16810.0us expected=16910.2us +- 84.6us, error 0.6%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.733597/4061, current 188.750412/4062: elapsed=16810.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.750412
last_received_ts = 188.749786
last_seq = 4062
current_ts = 188.767197
current_received_ts = 188.766586
current_seq = 4063
count = 408
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.750412/4062, current 188.767197/4063: elapsed=16782.0us expected=16910.2us +- 84.6us, error 0.8%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.750412/4062, current 188.767197/4063: elapsed=16782.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.767197
last_received_ts = 188.766586
last_seq = 4063
current_ts = 188.783997
current_received_ts = 188.783386
current_seq = 4064
count = 409
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.767197/4063, current 188.783997/4064: elapsed=16801.0us expected=16910.2us +- 84.6us, error 0.6%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.767197/4063, current 188.783997/4064: elapsed=16801.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.783997
last_received_ts = 188.783386
last_seq = 4064
current_ts = 188.800797
current_received_ts = 188.800140
current_seq = 4065
count = 410
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.783997/4064, current 188.800797/4065: elapsed=16802.0us expected=16910.2us +- 84.6us, error 0.6%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.783997/4064, current 188.800797/4065: elapsed=16802.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.800797
last_received_ts = 188.800140
last_seq = 4065
current_ts = 188.817581
current_received_ts = 188.817062
current_seq = 4066
count = 411
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.800797/4065, current 188.817581/4066: elapsed=16793.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.800797/4065, current 188.817581/4066: elapsed=16793.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.817581
last_received_ts = 188.817062
last_seq = 4066
current_ts = 188.834381
current_received_ts = 188.833786
current_seq = 4067
count = 412
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.817581/4066, current 188.834381/4067: elapsed=16798.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.817581/4066, current 188.834381/4067: elapsed=16798.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.834381
last_received_ts = 188.833786
last_seq = 4067
current_ts = 188.851196
current_received_ts = 188.850601
current_seq = 4068
count = 413
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.834381/4067, current 188.851196/4068: elapsed=16812.0us expected=16910.2us +- 84.6us, error 0.6%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.834381/4067, current 188.851196/4068: elapsed=16812.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.851196
last_received_ts = 188.850601
last_seq = 4068
current_ts = 188.867981
current_received_ts = 188.867401
current_seq = 4069
count = 414
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.851196/4068, current 188.867981/4069: elapsed=16781.0us expected=16910.2us +- 84.6us, error 0.8%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.851196/4068, current 188.867981/4069: elapsed=16781.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.867981
last_received_ts = 188.867401
last_seq = 4069
current_ts = 188.884781
current_received_ts = 188.884201
current_seq = 4070
count = 415
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.867981/4069, current 188.884781/4070: elapsed=16807.0us expected=16910.2us +- 84.6us, error 0.6%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.867981/4069, current 188.884781/4070: elapsed=16807.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.884781
last_received_ts = 188.884201
last_seq = 4070
current_ts = 188.901581
current_received_ts = 188.900986
current_seq = 4071
count = 416
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.884781/4070, current 188.901581/4071: elapsed=16792.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.884781/4070, current 188.901581/4071: elapsed=16792.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.901581
last_received_ts = 188.900986
last_seq = 4071
current_ts = 188.918381
current_received_ts = 188.917816
current_seq = 4072
count = 417
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.901581/4071, current 188.918381/4072: elapsed=16802.0us expected=16910.2us +- 84.6us, error 0.6%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.901581/4071, current 188.918381/4072: elapsed=16802.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.918381
last_received_ts = 188.917816
last_seq = 4072
current_ts = 188.935165
current_received_ts = 188.934555
current_seq = 4073
count = 418
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.918381/4072, current 188.935165/4073: elapsed=16792.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.918381/4072, current 188.935165/4073: elapsed=16792.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.935165
last_received_ts = 188.934555
last_seq = 4073
current_ts = 188.951965
current_received_ts = 188.951340
current_seq = 4074
count = 419
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.935165/4073, current 188.951965/4074: elapsed=16799.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.935165/4073, current 188.951965/4074: elapsed=16799.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.951965
last_received_ts = 188.951340
last_seq = 4074
current_ts = 188.968781
current_received_ts = 188.968079
current_seq = 4075
count = 420
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.951965/4074, current 188.968781/4075: elapsed=16812.0us expected=16910.2us +- 84.6us, error 0.6%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.951965/4074, current 188.968781/4075: elapsed=16812.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.968781
last_received_ts = 188.968079
last_seq = 4075
current_ts = 188.985565
current_received_ts = 188.985001
current_seq = 4076
count = 421
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.968781/4075, current 188.985565/4076: elapsed=16783.0us expected=16910.2us +- 84.6us, error 0.8%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.968781/4075, current 188.985565/4076: elapsed=16783.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 188.985565
last_received_ts = 188.985001
last_seq = 4076
current_ts = 189.002365
current_received_ts = 189.001755
current_seq = 4077
count = 422
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 188.985565/4076, current 189.002365/4077: elapsed=16798.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 188.985565/4076, current 189.002365/4077: elapsed=16798.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 189.002365
last_received_ts = 189.001755
last_seq = 4077
current_ts = 189.019165
current_received_ts = 189.018448
current_seq = 4078
count = 423
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 189.002365/4077, current 189.019165/4078: elapsed=16808.0us expected=16910.2us +- 84.6us, error 0.6%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 189.002365/4077, current 189.019165/4078: elapsed=16808.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 189.019165
last_received_ts = 189.018448
last_seq = 4078
current_ts = 189.035950
current_received_ts = 189.035294
current_seq = 4079
count = 424
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 189.019165/4078, current 189.035950/4079: elapsed=16784.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 189.019165/4078, current 189.035950/4079: elapsed=16784.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 189.035950
last_received_ts = 189.035294
last_seq = 4079
current_ts = 189.052750
current_received_ts = 189.052170
current_seq = 4080
count = 425
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 189.035950/4079, current 189.052750/4080: elapsed=16800.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 189.035950/4079, current 189.052750/4080: elapsed=16800.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 189.052750
last_received_ts = 189.052170
last_seq = 4080
current_ts = 189.069565
current_received_ts = 189.068878
current_seq = 4081
count = 426
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 189.052750/4080, current 189.069565/4081: elapsed=16815.0us expected=16910.2us +- 84.6us, error 0.6%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 189.052750/4080, current 189.069565/4081: elapsed=16815.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 189.069565
last_received_ts = 189.068878
last_seq = 4081
current_ts = 189.086349
current_received_ts = 189.085739
current_seq = 4082
count = 427
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 189.069565/4081, current 189.086349/4082: elapsed=16783.0us expected=16910.2us +- 84.6us, error 0.8%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 189.069565/4081, current 189.086349/4082: elapsed=16783.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 189.086349
last_received_ts = 189.085739
last_seq = 4082
current_ts = 189.103149
current_received_ts = 189.102554
current_seq = 4083
count = 428
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 189.086349/4082, current 189.103149/4083: elapsed=16798.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 189.086349/4082, current 189.103149/4083: elapsed=16798.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 189.103149
last_received_ts = 189.102554
last_seq = 4083
current_ts = 189.119949
current_received_ts = 189.119339
current_seq = 4084
count = 429
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 189.103149/4083, current 189.119949/4084: elapsed=16800.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 189.103149/4083, current 189.119949/4084: elapsed=16800.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 189.119949
last_received_ts = 189.119339
last_seq = 4084
current_ts = 189.136765
current_received_ts = 189.136124
current_seq = 4085
count = 430
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 189.119949/4084, current 189.136765/4085: elapsed=16805.0us expected=16910.2us +- 84.6us, error 0.6%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 189.119949/4084, current 189.136765/4085: elapsed=16805.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 189.136765
last_received_ts = 189.136124
last_seq = 4085
current_ts = 189.153534
current_received_ts = 189.152924
current_seq = 4086
count = 431
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 189.136765/4085, current 189.153534/4086: elapsed=16783.0us expected=16910.2us +- 84.6us, error 0.8%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 189.136765/4085, current 189.153534/4086: elapsed=16783.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 189.153534
last_received_ts = 189.152924
last_seq = 4086
current_ts = 189.170319
current_received_ts = 189.169724
current_seq = 4087
count = 432
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 189.153534/4086, current 189.170319/4087: elapsed=16781.0us expected=16910.2us +- 84.6us, error 0.8%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 189.153534/4086, current 189.170319/4087: elapsed=16781.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 189.170319
last_received_ts = 189.169724
last_seq = 4087
current_ts = 189.187103
current_received_ts = 189.186508
current_seq = 4088
count = 433
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 189.170319/4087, current 189.187103/4088: elapsed=16788.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 189.170319/4087, current 189.187103/4088: elapsed=16788.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 189.187103
last_received_ts = 189.186508
last_seq = 4088
current_ts = 189.203918
current_received_ts = 189.203247
current_seq = 4089
count = 434
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 189.187103/4088, current 189.203918/4089: elapsed=16803.0us expected=16910.2us +- 84.6us, error 0.6%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 189.187103/4088, current 189.203918/4089: elapsed=16803.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 189.203918
last_received_ts = 189.203247
last_seq = 4089
current_ts = 189.220688
current_received_ts = 189.220093
current_seq = 4090
count = 435
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 189.203918/4089, current 189.220688/4090: elapsed=16777.0us expected=16910.2us +- 84.6us, error 0.8%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 189.203918/4089, current 189.220688/4090: elapsed=16777.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 189.220688
last_received_ts = 189.220093
last_seq = 4090
current_ts = 189.237473
current_received_ts = 189.236923
current_seq = 4091
count = 436
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 189.220688/4090, current 189.237473/4091: elapsed=16786.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 189.220688/4090, current 189.237473/4091: elapsed=16786.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 189.237473
last_received_ts = 189.236923
last_seq = 4091
current_ts = 189.254272
current_received_ts = 189.253723
current_seq = 4092
count = 437
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 189.237473/4091, current 189.254272/4092: elapsed=16798.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 189.237473/4091, current 189.254272/4092: elapsed=16798.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 189.254272
last_received_ts = 189.253723
last_seq = 4092
current_ts = 189.271057
current_received_ts = 189.270416
current_seq = 4093
count = 438
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 189.254272/4092, current 189.271057/4093: elapsed=16791.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 189.254272/4092, current 189.271057/4093: elapsed=16791.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 189.271057
last_received_ts = 189.270416
last_seq = 4093
current_ts = 189.287857
current_received_ts = 189.287155
current_seq = 4094
count = 439
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 189.271057/4093, current 189.287857/4094: elapsed=16788.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 189.271057/4093, current 189.287857/4094: elapsed=16788.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 189.287857
last_received_ts = 189.287155
last_seq = 4094
current_ts = 189.304642
current_received_ts = 189.304092
current_seq = 4095
count = 440
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 189.287857/4094, current 189.304642/4095: elapsed=16794.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 189.287857/4094, current 189.304642/4095: elapsed=16794.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 189.304642
last_received_ts = 189.304092
last_seq = 4095
current_ts = 189.321426
current_received_ts = 189.320786
current_seq = 4096
count = 441
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 189.304642/4095, current 189.321426/4096: elapsed=16779.0us expected=16910.2us +- 84.6us, error 0.8%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 189.304642/4095, current 189.321426/4096: elapsed=16779.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 189.321426
last_received_ts = 189.320786
last_seq = 4096
current_ts = 189.338211
current_received_ts = 189.337555
current_seq = 4097
count = 442
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 189.321426/4096, current 189.338211/4097: elapsed=16779.0us expected=16910.2us +- 84.6us, error 0.8%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 189.321426/4096, current 189.338211/4097: elapsed=16779.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 189.338211
last_received_ts = 189.337555
last_seq = 4097
current_ts = 189.355011
current_received_ts = 189.354279
current_seq = 4098
count = 443
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 189.338211/4097, current 189.355011/4098: elapsed=16804.0us expected=16910.2us +- 84.6us, error 0.6%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 189.338211/4097, current 189.355011/4098: elapsed=16804.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 189.355011
last_received_ts = 189.354279
last_seq = 4098
current_ts = 189.371796
current_received_ts = 189.371170
current_seq = 4099
count = 444
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 189.355011/4098, current 189.371796/4099: elapsed=16795.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 189.355011/4098, current 189.371796/4099: elapsed=16795.0us expected=16910.2us
(kms_flip:2717) DEBUG: name = flip
last_ts = 189.371796
last_received_ts = 189.371170
last_seq = 4099
current_ts = 189.388596
current_received_ts = 189.387924
current_seq = 4100
count = 445
seq_step = 1
(kms_flip:2717) DEBUG: flip ts/seq: last 189.371796/4099, current 189.388596/4100: elapsed=16786.0us expected=16910.2us +- 84.6us, error 0.7%
(kms_flip:2717) DEBUG: inconsistent flip ts/seq: last 189.371796/4099, current 189.388596/4100: elapsed=16786.0us expected=16910.2us
(kms_flip:2717) INFO: Event flip: expected 444, counted 446, passrate = 57.85%, encoder type 1
(kms_flip:2717) DEBUG: dropped frames, expected 444, counted 446, passrate = 57.85%, encoder type 1
(kms_flip:2717) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1709:
(kms_flip:2717) CRITICAL: Failed assertion: !retried
(kms_flip:2717) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:2717) igt_core-INFO: Stack trace:
(kms_flip:2717) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2717) igt_core-INFO:   #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
(kms_flip:2717) igt_core-INFO:   #2 ../tests/kms_flip.c:1889 run_test()
(kms_flip:2717) igt_core-INFO:   #3 ../tests/kms_flip.c:2158 __igt_unique____real_main2045()
(kms_flip:2717) igt_core-INFO:   #4 ../tests/kms_flip.c:2045 main()
(kms_flip:2717) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2717) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2717) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-VGA1: FAIL (18.678s)
Dmesg
<6> [169.865500] Console: switching to colour dummy device 80x25
<6> [169.865617] [IGT] kms_flip: executing
<7> [169.897020] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [169.897939] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [169.898452] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [169.901782] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:71:VGA-1]
<7> [169.901793] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:71:VGA-1] force=1
<7> [169.902064] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] ironlake hotplug adpa=0xf40000, result 0
<7> [169.902284] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT not detected via hotplug
<7> [169.902677] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [169.902952] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7> [169.903323] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [169.903549] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [169.903557] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7> [169.903821] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7> [169.905089] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [169.905095] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7> [169.905320] i915 0000:00:02.0: [drm:intel_crt_detect_ddc [i915]] CRT not detected via DDC:0x50 [no valid EDID found]
<7> [169.905579] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:71:VGA-1] disconnected
<7> [169.906088] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:82:DP-1]
<7> [169.906095] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:82:DP-1]
<7> [169.908880] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [169.911755] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [169.914628] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [169.917590] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [169.920463] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [169.923334] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [169.926425] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [169.929270] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [169.932103] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [169.934962] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [169.937848] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [169.940715] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [169.943593] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [169.946472] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [169.949356] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [169.952234] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [169.955135] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [169.958047] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [169.960955] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [169.963890] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [169.966917] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [169.970022] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [169.973097] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [169.976221] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [169.979342] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [169.982463] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [169.985573] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [169.988725] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [169.991847] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [169.994959] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [169.998004] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.001056] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.001515] 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> [170.004119] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.007048] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.010125] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.013197] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.016267] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.019337] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.022431] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.025525] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.028592] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.031720] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.034777] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.037882] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.041005] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.044028] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.047086] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.050165] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.053250] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.056371] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.059500] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.062625] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.065766] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.068902] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.072016] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.075090] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.078192] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.081309] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.084388] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.087427] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.090461] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.093533] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.096650] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.099748] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.100223] 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> [170.100260] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:82:DP-1] disconnected
<7> [170.100677] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:71:VGA-1]
<7> [170.100694] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:71:VGA-1] force=1
<7> [170.101225] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] ironlake hotplug adpa=0xf40000, result 0
<7> [170.101780] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT not detected via hotplug
<7> [170.102503] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [170.102872] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7> [170.103400] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [170.103761] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [170.103769] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7> [170.104115] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7> [170.105635] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [170.105652] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7> [170.105991] i915 0000:00:02.0: [drm:intel_crt_detect_ddc [i915]] CRT not detected via DDC:0x50 [no valid EDID found]
<7> [170.106354] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:71:VGA-1] disconnected
<7> [170.106764] i915 0000:00:02.0: [drm:status_store] [CONNECTOR:71:VGA-1] force updated from 0 to 2 or reprobing
<7> [170.106783] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:71:VGA-1]
<7> [170.106792] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:71:VGA-1] status updated from disconnected to connected
<7> [170.106819] i915 0000:00:02.0: [drm:drm_sysfs_hotplug_event] generating hotplug event
<7> [170.106929] i915 0000:00:02.0: [drm:drm_client_hotplug] fbdev: ret=0
<7> [170.107138] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [170.107404] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7> [170.107779] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [170.107997] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [170.108010] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7> [170.108269] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7> [170.109510] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [170.109522] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7> [170.109800] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:71:VGA-1] probed modes:
<7> [170.109806] 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> [170.109812] 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> [170.109817] 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> [170.109822] 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> [170.109827] 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> [170.109875] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:71:VGA-1]
<7> [170.110083] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [170.110316] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7> [170.110691] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [170.110906] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [170.110917] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7> [170.111142] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7> [170.112336] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [170.112348] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7> [170.112631] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:71:VGA-1] probed modes:
<7> [170.112636] 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> [170.112642] 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> [170.112647] 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> [170.112652] 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> [170.112656] 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> [170.112678] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:74:HDMI-A-1]
<7> [170.112686] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:74:HDMI-A-1]
<7> [170.137886] 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> [170.137902] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:74:HDMI-A-1] HDMI sink does deep color 30.
<7> [170.137910] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:74:HDMI-A-1] HDMI sink does deep color 36.
<7> [170.137917] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:74:HDMI-A-1] HDMI sink does deep color 48.
<7> [170.137923] 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> [170.137930] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:74:HDMI-A-1] HDMI sink does YCRCB444 in deep color.
<7> [170.137940] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:74:HDMI-A-1] ELD monitor Mi TV
<7> [170.137949] 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> [170.137958] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:74:HDMI-A-1] ELD size 36, SAD count 3
<7> [170.138234] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK for addr: 0040 w(1)
<7> [170.138776] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK on first message, retry
<7> [170.139356] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK for addr: 0040 w(1)
<7> [170.139925] i915 0000:00:02.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [170.141654] 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> [170.141668] 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> [170.141677] 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> [170.141685] 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> [170.141692] 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> [170.141700] 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> [170.141707] 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> [170.141714] 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> [170.141722] 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> [170.141730] 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> [170.141737] 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> [170.141744] 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> [170.141751] 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> [170.141759] 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> [170.141766] 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> [170.141773] 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> [170.141781] 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> [170.141788] 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> [170.141795] 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> [170.141808] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:74:HDMI-A-1] probed modes:
<7> [170.141814] 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> [170.141819] 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> [170.141824] 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> [170.141829] 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> [170.141834] 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> [170.141838] 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> [170.141843] 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> [170.141848] 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> [170.141853] 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> [170.141858] 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> [170.141863] 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> [170.141868] 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> [170.141873] 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> [170.141878] 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> [170.141882] 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> [170.141887] 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> [170.141892] 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> [170.141896] 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> [170.141901] 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> [170.141906] 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> [170.141911] 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> [170.141916] 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> [170.141920] 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> [170.141925] 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> [170.141930] 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> [170.141934] 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> [170.141939] 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> [170.141944] 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> [170.141949] 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> [170.141953] 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> [170.141958] 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> [170.141963] 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> [170.141968] 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> [170.141972] 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> [170.141977] 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> [170.141982] 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> [170.141986] 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> [170.141991] 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> [170.141996] 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> [170.142001] 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> [170.142005] 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> [170.142010] 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> [170.142015] 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> [170.142019] 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> [170.142024] 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> [170.142093] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:82:DP-1]
<7> [170.142100] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:82:DP-1]
<7> [170.144921] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.147779] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.150628] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.153485] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.156329] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.159173] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.162016] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.164844] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.167689] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.170523] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.173377] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.176238] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.179089] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.181988] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.184869] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.187753] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.190619] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.193495] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.196405] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.199249] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.202094] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.204983] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.207832] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.210743] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.213871] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.217019] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.220080] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.223190] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.226284] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.229425] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.232512] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.235626] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.236172] 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> [170.238833] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.241852] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.244802] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.247781] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.250781] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.253848] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.257007] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.260070] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.263112] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.266225] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.269366] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.272440] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.275515] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.278540] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.281644] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.284707] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.287733] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.290741] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.293686] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.296637] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.299755] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.302865] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.305944] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.309060] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.312155] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.315291] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.318429] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.321380] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.324478] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.327631] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.330690] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.333715] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [170.334150] 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> [170.334269] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:82:DP-1] disconnected
<6> [170.342180] [IGT] kms_flip: starting subtest plain-flip-ts-check-interruptible
<6> [170.343341] [IGT] kms_flip: starting dynamic subtest A-VGA1
<7> [170.343883] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:86]
<7> [170.344038] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:90]
<7> [170.375659] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:51:pipe A]
<7> [170.375798] 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> [170.376072] 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> [170.376378] 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> [170.376621] 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> [170.376869] 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> [170.377127] 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> [170.377375] 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> [170.377606] 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> [170.377839] 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> [170.378086] 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> [170.378318] 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> [170.378552] 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> [170.378783] 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> [170.379046] 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> [170.379277] 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> [170.379508] 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> [170.379739] 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> [170.379987] 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> [170.380219] 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> [170.380449] 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> [170.380680] 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> [170.380926] 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> [170.381157] 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> [170.381388] 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> [170.381618] 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> [170.381849] 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> [170.382097] 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> [170.382328] 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> [170.382559] 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> [170.382789] 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> [170.383037] 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> [170.383268] 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> [170.383498] 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> [170.383729] 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> [170.383977] 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> [170.384209] 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> [170.384439] 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> [170.384670] 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> [170.384919] 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> [170.385150] 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> [170.385380] 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> [170.385611] 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> [170.385858] 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> [170.386090] 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> [170.386320] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in avi infoframe
<7> [170.386551] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [170.386798] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [170.386801] i915 0000:00:02.0: colorspace: RGB
<7> [170.386803] i915 0000:00:02.0: scan mode: Underscan
<7> [170.386805] i915 0000:00:02.0: colorimetry: No Data
<7> [170.386807] i915 0000:00:02.0: picture aspect: 16:9
<7> [170.386809] i915 0000:00:02.0: active aspect: Same as Picture
<7> [170.386811] i915 0000:00:02.0: itc: No Data
<7> [170.386813] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [170.386815] i915 0000:00:02.0: quantization range: Default
<7> [170.386817] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [170.386819] i915 0000:00:02.0: video code: 16
<7> [170.386821] i915 0000:00:02.0: ycc quantization range: Full
<7> [170.386823] i915 0000:00:02.0: hdmi content type: Graphics
<7> [170.386825] i915 0000:00:02.0: pixel repeat: 0
<7> [170.386827] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [170.386829] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [170.387060] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in spd infoframe
<7> [170.387290] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [170.387519] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [170.387522] i915 0000:00:02.0: vendor: Intel
<7> [170.387524] i915 0000:00:02.0: product: Integrated gfx
<7> [170.387526] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [170.387528] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [170.387776] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in hdmi infoframe
<7> [170.388007] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [170.388236] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [170.388239] i915 0000:00:02.0: empty frame
<7> [170.388240] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [170.388471] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:51:pipe A] fastset requirement not met, forcing full modeset
<7> [170.388718] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:51:pipe A] releasing PCH DPLL A
<7> [170.389007] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:51:pipe A] min cdclk: 165334 kHz -> 0 kHz
<7> [170.389327] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:51:pipe A] enable: no [modeset]
<7> [170.389584] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:33:primary A] fb: [NOFB], visible: no
<7> [170.389864] 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> [170.411194] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [170.427939] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found
<7> [170.428411] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found
<7> [170.428903] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found
<7> [170.439205] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PCH DPLL A (active 0x1, on? 1) for [CRTC:51:pipe A]
<7> [170.439706] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PCH DPLL A
<7> [170.440464] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:72:CRT]
<7> [170.440884] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:73:HDMI D]
<7> [170.441388] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:81:DP D]
<7> [170.441651] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:74:HDMI-A-1]
<7> [170.441919] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:51:pipe A]
<7> [170.442287] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:70:pipe B]
<7> [170.442427] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:51:pipe A]
<7> [170.442455] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:71:VGA-1]
<7> [170.442540] 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> [170.442787] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:51:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [170.443042] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe A, lanes 1
<7> [170.443290] 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> [170.443523] 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> [170.443754] 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> [170.444024] 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> [170.444274] 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> [170.444506] 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> [170.444738] 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> [170.444995] 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> [170.445259] 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> [170.445485] 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> [170.445721] 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> [170.445975] 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> [170.446235] 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> [170.446460] 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> [170.446684] 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> [170.446943] 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> [170.447210] 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> [170.447435] 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> [170.447685] 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> [170.447956] 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> [170.448180] 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> [170.448405] 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> [170.448664] 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> [170.448908] 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> [170.449140] 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> [170.449374] 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> [170.449631] 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> [170.449901] 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> [170.450125] 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> [170.450367] 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> [170.450648] 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> [170.450886] 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> [170.451111] 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> [170.451352] 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> [170.451632] 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> [170.451890] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:51:pipe A] fastset requirement not met in dpll_hw_state
<7> [170.452114] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [170.452338] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0xc4010001, dpll_md: 0x0, fp0: 0x30f06, fp1: 0x30f06
<7> [170.452602] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [170.452861] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [170.453086] 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> [170.453323] 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> [170.453576] 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> [170.453821] 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> [170.454046] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:51:pipe A] fastset requirement not met, forcing full modeset
<7> [170.454309] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:51:pipe A] min cdclk: 0 kHz -> 72000 kHz
<7> [170.454578] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:51:pipe A] allocated PCH DPLL A
<7> [170.454853] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:51:pipe A] reserving PCH DPLL A
<7> [170.455076] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:51:pipe A] enable: yes [modeset]
<7> [170.455352] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB
<7> [170.455614] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [170.455844] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [170.456073] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [170.456324] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [170.456581] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [170.456811] 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> [170.457041] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [170.457292] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1
<7> [170.457541] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [170.457771] 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> [170.458001] 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> [170.458254] 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> [170.458504] 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> [170.458735] 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> [170.458967] 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> [170.459227] 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> [170.459253] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [170.459483] 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> [170.459715] 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> [170.459945] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0
<7> [170.460214] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [170.460461] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [170.460691] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [170.460927] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [170.461196] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [170.461445] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [170.461668] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [170.461891] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [170.462149] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [170.462412] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [170.462636] 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> [170.462861] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [170.463134] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0
<7> [170.464834] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [170.465096] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:72:CRT]
<7> [170.465314] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:73:HDMI D]
<7> [170.465530] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:81:DP D]
<7> [170.466323] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [170.467389] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100
<7> [170.467782] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done.
<7> [170.468789] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x200
<7> [170.469132] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done.
<7> [170.469464] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train done.
<7> [170.469840] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PCH DPLL A (active 0x1, on? 0) for [CRTC:51:pipe A]
<7> [170.470126] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PCH DPLL A
<7> [170.522124] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:71:VGA-1]
<7> [170.522677] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:51:pipe A]
<7> [181.073485] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:51:pipe A]
<7> [181.073675] 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> [181.074279] 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> [181.074853] 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> [181.075407] 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> [181.075944] 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> [181.076457] 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> [181.076771] 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> [181.077242] 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> [181.077601] 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> [181.077833] 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> [181.078079] 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> [181.078329] 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> [181.078560] 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> [181.078791] 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> [181.079021] 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> [181.079281] 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> [181.079532] 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> [181.079763] 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> [181.079993] 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> [181.080243] 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> [181.080493] 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> [181.080723] 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> [181.081033] 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> [181.081356] 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> [181.081597] 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> [181.081835] 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> [181.082221] 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> [181.082510] 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> [181.082736] 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> [181.082968] 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> [181.083347] 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> [181.083590] 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> [181.083827] 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> [181.084149] 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> [181.084487] 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> [181.084713] 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> [181.084946] 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> [181.085331] 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> [181.085575] 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> [181.085810] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:51:pipe A] fastset requirement not met, forcing full modeset
<7> [181.086077] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:51:pipe A] releasing PCH DPLL A
<7> [181.086370] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:51:pipe A] min cdclk: 72000 kHz -> 0 kHz
<7> [181.086599] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:51:pipe A] enable: no [modeset]
<7> [181.086824] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:33:primary A] fb: [NOFB], visible: no
<7> [181.087213] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [181.105891] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found
<7> [181.106361] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found
<7> [181.106852] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found
<7> [181.120921] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PCH DPLL A (active 0x1, on? 1) for [CRTC:51:pipe A]
<7> [181.121423] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PCH DPLL A
<7> [181.122183] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:72:CRT]
<7> [181.122632] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:73:HDMI D]
<7> [181.123116] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:81:DP D]
<7> [181.123663] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:71:VGA-1]
<7> [181.124258] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:51:pipe A]
<7> [181.124907] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:70:pipe B]
<7> [181.125037] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:51:pipe A]
<7> [181.125060] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:71:VGA-1]
<7> [181.125139] 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> [181.125379] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:51:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [181.125641] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe A, lanes 1
<7> [181.125921] 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> [181.126147] 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> [181.126371] 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> [181.126638] 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> [181.126908] 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> [181.127133] 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> [181.127359] 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> [181.127629] 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> [181.127878] 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> [181.128109] 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> [181.128339] 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> [181.128585] 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> [181.128843] 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> [181.129074] 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> [181.129304] 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> [181.129549] 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> [181.129799] 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> [181.130029] 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> [181.130259] 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> [181.130508] 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> [181.130758] 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> [181.130989] 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> [181.131219] 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> [181.131471] 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> [181.131719] 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> [181.131960] 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> [181.132184] 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> [181.132437] 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> [181.132683] 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> [181.132907] 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> [181.133131] 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> [181.133355] 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> [181.133613] 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> [181.133864] 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> [181.134106] 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> [181.134331] 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> [181.134590] 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> [181.134833] 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> [181.135057] 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> [181.135281] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:51:pipe A] fastset requirement not met, forcing full modeset
<7> [181.135597] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:51:pipe A] min cdclk: 0 kHz -> 72000 kHz
<7> [181.135826] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:51:pipe A] allocated PCH DPLL A
<7> [181.136056] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:51:pipe A] reserving PCH DPLL A
<7> [181.136299] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:51:pipe A] enable: yes [modeset]
<7> [181.136593] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB
<7> [181.136817] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [181.137056] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [181.137289] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [181.137549] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [181.137801] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [181.138031] 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> [181.138261] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [181.138525] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1
<7> [181.138780] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [181.139014] 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> [181.139244] 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> [181.139505] 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> [181.139747] 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> [181.139986] 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> [181.140216] 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> [181.140466] 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> [181.140731] 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> [181.140956] 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> [181.141188] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0
<7> [181.141438] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [181.141680] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [181.141914] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [181.142144] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [181.142427] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [181.142676] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [181.142911] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [181.143144] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [181.143310] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [181.143413] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [181.143652] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [181.143904] 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> [181.144136] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [181.144390] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0
<7> [181.144695] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [181.144925] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:72:CRT]
<7> [181.145149] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:73:HDMI D]
<7> [181.145410] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:81:DP D]
<7> [181.146230] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [181.147334] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100
<7> [181.147554] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done.
<7> [181.148443] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x200
<7> [181.148679] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done.
<7> [181.148896] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train done.
<7> [181.149128] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PCH DPLL A (active 0x1, on? 0) for [CRTC:51:pipe A]
<7> [181.149384] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PCH DPLL A
<7> [181.201387] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:71:VGA-1]
<7> [181.201985] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:51:pipe A]
<6> [189.021332] [IGT] kms_flip: finished subtest A-VGA1, FAIL
Created at 2026-01-12 21:19:30