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

Result: Fail

git-log-oneline i915_display_info9 igt_runner9 runtimes9 results9.json results9-i915-load.json guc_logs9.tar i915_display_info_post_exec9 boot9 dmesg9

DetailValue
Duration 18.87 seconds
Hostname 
shard-snb7
Igt-Version 
IGT-Version: 2.4-g96c7268b7 (x86_64) (Linux: 7.0.0-rc6-CI_DRM_18270-g7b7e57c49dff+ x86_64)
Out 
Using IGT_SRANDOM=1775201473 for randomisation
Opened device: /dev/dri/card0
Using monotonic timestamps
Starting subtest: flip-vs-absolute-wf_vblank-interruptible
Starting dynamic subtest: A-VGA1
  1024x768: 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa 
Expected frametime: 16666us; measured 17420.1us +- 9.258us accuracy 0.16%
vblank interval differs from modeline! expected 16665.6us, measured 17420us +- 9.258us, difference 754.5us (81.5 sigma)
Event flip: expected 433, counted 440, passrate = 15.91%, encoder type 1
Event vblank: expected 433, counted 440, passrate = 9.09%, encoder type 1
Expected frametime: 16666us; measured 16885.4us +- 10.494us accuracy 0.19%
vblank interval differs from modeline! expected 16665.6us, measured 16885us +- 10.494us, difference 219.8us (20.9 sigma)
Event flip: expected 448, counted 450, passrate = 71.11%, encoder type 1
Event vblank: expected 448, counted 450, passrate = 68.89%, 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:1891 run_test()
  #3 ../tests/kms_flip.c:2162 __igt_unique____real_main2047()
  #4 ../tests/kms_flip.c:2047 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest A-VGA1: FAIL (18.872s)
Err 
Starting subtest: flip-vs-absolute-wf_vblank-interruptible
Starting dynamic subtest: A-VGA1
(kms_flip:2586) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1711:
(kms_flip:2586) CRITICAL: Failed assertion: !retried
(kms_flip:2586) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest A-VGA1 failed.
**** DEBUG ****
(kms_flip:2586) DEBUG: name = flip
last_ts = 142.052078
last_received_ts = 142.051453
last_seq = 4336
current_ts = 142.222061
current_received_ts = 142.221329
current_seq = 4346
count = 40
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 142.052078/4336, current 142.222061/4346: elapsed=169982.0us expected=174201.2us +- 871.0us, error 2.4%
(kms_flip:2586) DEBUG: inconsistent flip ts/seq: last 142.052078/4336, current 142.222061/4346: elapsed=169982.0us expected=174201.2us
(kms_flip:2586) DEBUG: name = vblank
last_ts = 142.205063
last_received_ts = 142.204437
last_seq = 4345
current_ts = 142.375046
current_received_ts = 142.374329
current_seq = 4355
count = 40
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 142.205063/4345, current 142.375046/4355: elapsed=169989.0us expected=174201.2us +- 871.0us, error 2.4%
(kms_flip:2586) DEBUG: inconsistent vblank ts/seq: last 142.205063/4345, current 142.375046/4355: elapsed=169989.0us expected=174201.2us
(kms_flip:2586) DEBUG: name = flip
last_ts = 142.222061
last_received_ts = 142.221329
last_seq = 4346
current_ts = 142.392044
current_received_ts = 142.391403
current_seq = 4356
count = 41
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 142.222061/4346, current 142.392044/4356: elapsed=169992.0us expected=174201.2us +- 871.0us, error 2.4%
(kms_flip:2586) DEBUG: inconsistent flip ts/seq: last 142.222061/4346, current 142.392044/4356: elapsed=169992.0us expected=174201.2us
(kms_flip:2586) DEBUG: name = vblank
last_ts = 142.375046
last_received_ts = 142.374329
last_seq = 4355
current_ts = 142.545029
current_received_ts = 142.544388
current_seq = 4365
count = 41
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 142.375046/4355, current 142.545029/4365: elapsed=169981.0us expected=174201.2us +- 871.0us, error 2.4%
(kms_flip:2586) DEBUG: inconsistent vblank ts/seq: last 142.375046/4355, current 142.545029/4365: elapsed=169981.0us expected=174201.2us
(kms_flip:2586) DEBUG: name = flip
last_ts = 142.392044
last_received_ts = 142.391403
last_seq = 4356
current_ts = 142.562012
current_received_ts = 142.561386
current_seq = 4366
count = 42
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 142.392044/4356, current 142.562012/4366: elapsed=169967.0us expected=174201.2us +- 871.0us, error 2.4%
(kms_flip:2586) DEBUG: inconsistent flip ts/seq: last 142.392044/4356, current 142.562012/4366: elapsed=169967.0us expected=174201.2us
(kms_flip:2586) DEBUG: name = vblank
last_ts = 142.545029
last_received_ts = 142.544388
last_seq = 4365
current_ts = 142.714783
current_received_ts = 142.714127
current_seq = 4375
count = 42
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 142.545029/4365, current 142.714783/4375: elapsed=169750.0us expected=174201.2us +- 871.0us, error 2.6%
(kms_flip:2586) DEBUG: inconsistent vblank ts/seq: last 142.545029/4365, current 142.714783/4375: elapsed=169750.0us expected=174201.2us
(kms_flip:2586) DEBUG: name = flip
last_ts = 142.562012
last_received_ts = 142.561386
last_seq = 4366
current_ts = 142.731735
current_received_ts = 142.731110
current_seq = 4376
count = 43
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 142.562012/4366, current 142.731735/4376: elapsed=169723.0us expected=174201.2us +- 871.0us, error 2.6%
(kms_flip:2586) DEBUG: inconsistent flip ts/seq: last 142.562012/4366, current 142.731735/4376: elapsed=169723.0us expected=174201.2us
(kms_flip:2586) DEBUG: name = vblank
last_ts = 142.714783
last_received_ts = 142.714127
last_seq = 4375
current_ts = 142.884399
current_received_ts = 142.883835
current_seq = 4385
count = 43
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 142.714783/4375, current 142.884399/4385: elapsed=169625.0us expected=174201.2us +- 871.0us, error 2.6%
(kms_flip:2586) DEBUG: inconsistent vblank ts/seq: last 142.714783/4375, current 142.884399/4385: elapsed=169625.0us expected=174201.2us
(kms_flip:2586) INFO: Event flip: expected 433, counted 440, passrate = 15.91%, encoder type 1
(kms_flip:2586) DEBUG: dropped frames, expected 433, counted 440, passrate = 15.91%, encoder type 1
(kms_flip:2586) INFO: Event vblank: expected 433, counted 440, passrate = 9.09%, encoder type 1
(kms_flip:2586) DEBUG: dropped frames, expected 433, counted 440, passrate = 9.09%, encoder type 1
(kms_flip:2586) DEBUG: Retrying without a hotplug event
(kms_flip:2586) DEBUG: No stale events found
(kms_flip:2586) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:2586) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:2586) INFO: Expected frametime: 16666us; measured 16885.4us +- 10.494us accuracy 0.19%
(kms_flip:2586) INFO: vblank interval differs from modeline! expected 16665.6us, measured 16885us +- 10.494us, difference 219.8us (20.9 sigma)
(kms_flip:2586) DEBUG: name = flip
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 146.336273
current_received_ts = 146.335709
current_seq = 4409
count = 0
seq_step = 1
(kms_flip:2586) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 146.488235
current_received_ts = 146.487595
current_seq = 4418
count = 0
seq_step = 10
(kms_flip:2586) DEBUG: name = flip
last_ts = 146.336273
last_received_ts = 146.335709
last_seq = 4409
current_ts = 146.505142
current_received_ts = 146.504532
current_seq = 4419
count = 1
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 146.336273/4409, current 146.505142/4419: elapsed=168866.0us expected=168853.7us +- 844.3us, error 0.0%
(kms_flip:2586) DEBUG: name = vblank
last_ts = 146.488235
last_received_ts = 146.487595
last_seq = 4418
current_ts = 146.657043
current_received_ts = 146.656403
current_seq = 4428
count = 1
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 146.488235/4418, current 146.657043/4428: elapsed=168798.0us expected=168853.7us +- 844.3us, error 0.0%
(kms_flip:2586) DEBUG: name = flip
last_ts = 146.505142
last_received_ts = 146.504532
last_seq = 4419
current_ts = 146.673889
current_received_ts = 146.673218
current_seq = 4429
count = 2
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 146.505142/4419, current 146.673889/4429: elapsed=168752.0us expected=168853.7us +- 844.3us, error 0.1%
(kms_flip:2586) DEBUG: name = vblank
last_ts = 146.657043
last_received_ts = 146.656403
last_seq = 4428
current_ts = 146.825668
current_received_ts = 146.825089
current_seq = 4438
count = 2
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 146.657043/4428, current 146.825668/4438: elapsed=168637.0us expected=168853.7us +- 844.3us, error 0.1%
(kms_flip:2586) DEBUG: name = flip
last_ts = 146.673889
last_received_ts = 146.673218
last_seq = 4429
current_ts = 146.842545
current_received_ts = 146.841919
current_seq = 4439
count = 3
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 146.673889/4429, current 146.842545/4439: elapsed=168652.0us expected=168853.7us +- 844.3us, error 0.1%
(kms_flip:2586) DEBUG: name = vblank
last_ts = 146.825668
last_received_ts = 146.825089
last_seq = 4438
current_ts = 146.994324
current_received_ts = 146.993744
current_seq = 4448
count = 3
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 146.825668/4438, current 146.994324/4448: elapsed=168653.0us expected=168853.7us +- 844.3us, error 0.1%
(kms_flip:2586) DEBUG: name = flip
last_ts = 146.842545
last_received_ts = 146.841919
last_seq = 4439
current_ts = 147.011200
current_received_ts = 147.010590
current_seq = 4449
count = 4
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 146.842545/4439, current 147.011200/4449: elapsed=168651.0us expected=168853.7us +- 844.3us, error 0.1%
(kms_flip:2586) DEBUG: name = vblank
last_ts = 146.994324
last_received_ts = 146.993744
last_seq = 4448
current_ts = 147.162979
current_received_ts = 147.162338
current_seq = 4458
count = 4
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 146.994324/4448, current 147.162979/4458: elapsed=168655.0us expected=168853.7us +- 844.3us, error 0.1%
(kms_flip:2586) DEBUG: name = flip
last_ts = 147.011200
last_received_ts = 147.010590
last_seq = 4449
current_ts = 147.179855
current_received_ts = 147.179214
current_seq = 4459
count = 5
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 147.011200/4449, current 147.179855/4459: elapsed=168665.0us expected=168853.7us +- 844.3us, error 0.1%
(kms_flip:2586) DEBUG: name = vblank
last_ts = 147.162979
last_received_ts = 147.162338
last_seq = 4458
current_ts = 147.331635
current_received_ts = 147.331070
current_seq = 4468
count = 5
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 147.162979/4458, current 147.331635/4468: elapsed=168650.0us expected=168853.7us +- 844.3us, error 0.1%
(kms_flip:2586) DEBUG: name = flip
last_ts = 147.179855
last_received_ts = 147.179214
last_seq = 4459
current_ts = 147.348495
current_received_ts = 147.347900
current_seq = 4469
count = 6
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 147.179855/4459, current 147.348495/4469: elapsed=168639.0us expected=168853.7us +- 844.3us, error 0.1%
(kms_flip:2586) DEBUG: name = vblank
last_ts = 147.331635
last_received_ts = 147.331070
last_seq = 4468
current_ts = 147.500305
current_received_ts = 147.499680
current_seq = 4478
count = 6
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 147.331635/4468, current 147.500305/4478: elapsed=168667.0us expected=168853.7us +- 844.3us, error 0.1%
(kms_flip:2586) DEBUG: name = flip
last_ts = 147.348495
last_received_ts = 147.347900
last_seq = 4469
current_ts = 147.517166
current_received_ts = 147.516525
current_seq = 4479
count = 7
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 147.348495/4469, current 147.517166/4479: elapsed=168662.0us expected=168853.7us +- 844.3us, error 0.1%
(kms_flip:2586) DEBUG: name = vblank
last_ts = 147.500305
last_received_ts = 147.499680
last_seq = 4478
current_ts = 147.668884
current_received_ts = 147.668213
current_seq = 4488
count = 7
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 147.500305/4478, current 147.668884/4488: elapsed=168579.0us expected=168853.7us +- 844.3us, error 0.2%
(kms_flip:2586) DEBUG: name = flip
last_ts = 147.517166
last_received_ts = 147.516525
last_seq = 4479
current_ts = 147.685730
current_received_ts = 147.685120
current_seq = 4489
count = 8
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 147.517166/4479, current 147.685730/4489: elapsed=168568.0us expected=168853.7us +- 844.3us, error 0.2%
(kms_flip:2586) DEBUG: name = vblank
last_ts = 147.668884
last_received_ts = 147.668213
last_seq = 4488
current_ts = 147.837341
current_received_ts = 147.836731
current_seq = 4498
count = 8
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 147.668884/4488, current 147.837341/4498: elapsed=168464.0us expected=168853.7us +- 844.3us, error 0.2%
(kms_flip:2586) DEBUG: name = flip
last_ts = 147.685730
last_received_ts = 147.685120
last_seq = 4489
current_ts = 147.854187
current_received_ts = 147.853546
current_seq = 4499
count = 9
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 147.685730/4489, current 147.854187/4499: elapsed=168462.0us expected=168853.7us +- 844.3us, error 0.2%
(kms_flip:2586) DEBUG: name = vblank
last_ts = 147.837341
last_received_ts = 147.836731
last_seq = 4498
current_ts = 148.005814
current_received_ts = 148.005127
current_seq = 4508
count = 9
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 147.837341/4498, current 148.005814/4508: elapsed=168474.0us expected=168853.7us +- 844.3us, error 0.2%
(kms_flip:2586) DEBUG: name = flip
last_ts = 147.854187
last_received_ts = 147.853546
last_seq = 4499
current_ts = 148.022675
current_received_ts = 148.022049
current_seq = 4509
count = 10
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 147.854187/4499, current 148.022675/4509: elapsed=168483.0us expected=168853.7us +- 844.3us, error 0.2%
(kms_flip:2586) DEBUG: name = vblank
last_ts = 148.005814
last_received_ts = 148.005127
last_seq = 4508
current_ts = 148.174301
current_received_ts = 148.173706
current_seq = 4518
count = 10
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 148.005814/4508, current 148.174301/4518: elapsed=168483.0us expected=168853.7us +- 844.3us, error 0.2%
(kms_flip:2586) DEBUG: name = flip
last_ts = 148.022675
last_received_ts = 148.022049
last_seq = 4509
current_ts = 148.191147
current_received_ts = 148.190521
current_seq = 4519
count = 11
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 148.022675/4509, current 148.191147/4519: elapsed=168470.0us expected=168853.7us +- 844.3us, error 0.2%
(kms_flip:2586) DEBUG: name = vblank
last_ts = 148.174301
last_received_ts = 148.173706
last_seq = 4518
current_ts = 148.342789
current_received_ts = 148.342117
current_seq = 4528
count = 11
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 148.174301/4518, current 148.342789/4528: elapsed=168483.0us expected=168853.7us +- 844.3us, error 0.2%
(kms_flip:2586) DEBUG: name = flip
last_ts = 148.191147
last_received_ts = 148.190521
last_seq = 4519
current_ts = 148.359619
current_received_ts = 148.359055
current_seq = 4529
count = 12
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 148.191147/4519, current 148.359619/4529: elapsed=168480.0us expected=168853.7us +- 844.3us, error 0.2%
(kms_flip:2586) DEBUG: name = vblank
last_ts = 148.342789
last_received_ts = 148.342117
last_seq = 4528
current_ts = 148.511261
current_received_ts = 148.510651
current_seq = 4538
count = 12
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 148.342789/4528, current 148.511261/4538: elapsed=168485.0us expected=168853.7us +- 844.3us, error 0.2%
(kms_flip:2586) DEBUG: name = flip
last_ts = 148.359619
last_received_ts = 148.359055
last_seq = 4529
current_ts = 148.528107
current_received_ts = 148.527466
current_seq = 4539
count = 13
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 148.359619/4529, current 148.528107/4539: elapsed=168485.0us expected=168853.7us +- 844.3us, error 0.2%
(kms_flip:2586) DEBUG: name = vblank
last_ts = 148.511261
last_received_ts = 148.510651
last_seq = 4538
current_ts = 148.679657
current_received_ts = 148.679001
current_seq = 4548
count = 13
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 148.511261/4538, current 148.679657/4548: elapsed=168386.0us expected=168853.7us +- 844.3us, error 0.3%
(kms_flip:2586) DEBUG: name = flip
last_ts = 148.528107
last_received_ts = 148.527466
last_seq = 4539
current_ts = 148.696487
current_received_ts = 148.695877
current_seq = 4549
count = 14
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 148.528107/4539, current 148.696487/4549: elapsed=168383.0us expected=168853.7us +- 844.3us, error 0.3%
(kms_flip:2586) DEBUG: name = vblank
last_ts = 148.679657
last_received_ts = 148.679001
last_seq = 4548
current_ts = 148.847977
current_received_ts = 148.847397
current_seq = 4558
count = 14
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 148.679657/4548, current 148.847977/4558: elapsed=168325.0us expected=168853.7us +- 844.3us, error 0.3%
(kms_flip:2586) DEBUG: name = flip
last_ts = 148.696487
last_received_ts = 148.695877
last_seq = 4549
current_ts = 148.864822
current_received_ts = 148.864166
current_seq = 4559
count = 15
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 148.696487/4549, current 148.864822/4559: elapsed=168338.0us expected=168853.7us +- 844.3us, error 0.3%
(kms_flip:2586) DEBUG: name = vblank
last_ts = 148.847977
last_received_ts = 148.847397
last_seq = 4558
current_ts = 149.016296
current_received_ts = 149.015701
current_seq = 4568
count = 15
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 148.847977/4558, current 149.016296/4568: elapsed=168326.0us expected=168853.7us +- 844.3us, error 0.3%
(kms_flip:2586) DEBUG: name = flip
last_ts = 148.864822
last_received_ts = 148.864166
last_seq = 4559
current_ts = 149.033142
current_received_ts = 149.032547
current_seq = 4569
count = 16
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 148.864822/4559, current 149.033142/4569: elapsed=168309.0us expected=168853.7us +- 844.3us, error 0.3%
(kms_flip:2586) DEBUG: name = vblank
last_ts = 149.016296
last_received_ts = 149.015701
last_seq = 4568
current_ts = 149.184631
current_received_ts = 149.183975
current_seq = 4578
count = 16
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 149.016296/4568, current 149.184631/4578: elapsed=168333.0us expected=168853.7us +- 844.3us, error 0.3%
(kms_flip:2586) DEBUG: name = flip
last_ts = 149.033142
last_received_ts = 149.032547
last_seq = 4569
current_ts = 149.201462
current_received_ts = 149.200867
current_seq = 4579
count = 17
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 149.033142/4569, current 149.201462/4579: elapsed=168323.0us expected=168853.7us +- 844.3us, error 0.3%
(kms_flip:2586) DEBUG: name = vblank
last_ts = 149.184631
last_received_ts = 149.183975
last_seq = 4578
current_ts = 149.352951
current_received_ts = 149.352295
current_seq = 4588
count = 17
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 149.184631/4578, current 149.352951/4588: elapsed=168321.0us expected=168853.7us +- 844.3us, error 0.3%
(kms_flip:2586) DEBUG: name = flip
last_ts = 149.201462
last_received_ts = 149.200867
last_seq = 4579
current_ts = 149.369781
current_received_ts = 149.369141
current_seq = 4589
count = 18
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 149.201462/4579, current 149.369781/4589: elapsed=168321.0us expected=168853.7us +- 844.3us, error 0.3%
(kms_flip:2586) DEBUG: name = vblank
last_ts = 149.352951
last_received_ts = 149.352295
last_seq = 4588
current_ts = 149.521286
current_received_ts = 149.520660
current_seq = 4598
count = 18
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 149.352951/4588, current 149.521286/4598: elapsed=168331.0us expected=168853.7us +- 844.3us, error 0.3%
(kms_flip:2586) DEBUG: name = flip
last_ts = 149.369781
last_received_ts = 149.369141
last_seq = 4589
current_ts = 149.538116
current_received_ts = 149.537491
current_seq = 4599
count = 19
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 149.369781/4589, current 149.538116/4599: elapsed=168328.0us expected=168853.7us +- 844.3us, error 0.3%
(kms_flip:2586) DEBUG: name = vblank
last_ts = 149.521286
last_received_ts = 149.520660
last_seq = 4598
current_ts = 149.689529
current_received_ts = 149.688873
current_seq = 4608
count = 19
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 149.521286/4598, current 149.689529/4608: elapsed=168241.0us expected=168853.7us +- 844.3us, error 0.4%
(kms_flip:2586) DEBUG: name = flip
last_ts = 149.538116
last_received_ts = 149.537491
last_seq = 4599
current_ts = 149.706345
current_received_ts = 149.705719
current_seq = 4609
count = 20
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 149.538116/4599, current 149.706345/4609: elapsed=168240.0us expected=168853.7us +- 844.3us, error 0.4%
(kms_flip:2586) DEBUG: name = vblank
last_ts = 149.689529
last_received_ts = 149.688873
last_seq = 4608
current_ts = 149.857727
current_received_ts = 149.857101
current_seq = 4618
count = 20
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 149.689529/4608, current 149.857727/4618: elapsed=168204.0us expected=168853.7us +- 844.3us, error 0.4%
(kms_flip:2586) DEBUG: name = flip
last_ts = 149.706345
last_received_ts = 149.705719
last_seq = 4609
current_ts = 149.874542
current_received_ts = 149.873917
current_seq = 4619
count = 21
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 149.706345/4609, current 149.874542/4619: elapsed=168191.0us expected=168853.7us +- 844.3us, error 0.4%
(kms_flip:2586) DEBUG: name = vblank
last_ts = 149.857727
last_received_ts = 149.857101
last_seq = 4618
current_ts = 150.025909
current_received_ts = 150.025284
current_seq = 4628
count = 21
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 149.857727/4618, current 150.025909/4628: elapsed=168178.0us expected=168853.7us +- 844.3us, error 0.4%
(kms_flip:2586) DEBUG: name = flip
last_ts = 149.874542
last_received_ts = 149.873917
last_seq = 4619
current_ts = 150.042740
current_received_ts = 150.042114
current_seq = 4629
count = 22
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 149.874542/4619, current 150.042740/4629: elapsed=168193.0us expected=168853.7us +- 844.3us, error 0.4%
(kms_flip:2586) DEBUG: name = vblank
last_ts = 150.025909
last_received_ts = 150.025284
last_seq = 4628
current_ts = 150.194092
current_received_ts = 150.193512
current_seq = 4638
count = 22
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 150.025909/4628, current 150.194092/4638: elapsed=168187.0us expected=168853.7us +- 844.3us, error 0.4%
(kms_flip:2586) DEBUG: name = flip
last_ts = 150.042740
last_received_ts = 150.042114
last_seq = 4629
current_ts = 150.210938
current_received_ts = 150.210327
current_seq = 4639
count = 23
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 150.042740/4629, current 150.210938/4639: elapsed=168200.0us expected=168853.7us +- 844.3us, error 0.4%
(kms_flip:2586) DEBUG: name = vblank
last_ts = 150.194092
last_received_ts = 150.193512
last_seq = 4638
current_ts = 150.362289
current_received_ts = 150.361725
current_seq = 4648
count = 23
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 150.194092/4638, current 150.362289/4648: elapsed=168187.0us expected=168853.7us +- 844.3us, error 0.4%
(kms_flip:2586) DEBUG: name = flip
last_ts = 150.210938
last_received_ts = 150.210327
last_seq = 4639
current_ts = 150.379105
current_received_ts = 150.378525
current_seq = 4649
count = 24
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 150.210938/4639, current 150.379105/4649: elapsed=168178.0us expected=168853.7us +- 844.3us, error 0.4%
(kms_flip:2586) DEBUG: name = vblank
last_ts = 150.362289
last_received_ts = 150.361725
last_seq = 4648
current_ts = 150.530487
current_received_ts = 150.529831
current_seq = 4658
count = 24
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 150.362289/4648, current 150.530487/4658: elapsed=168208.0us expected=168853.7us +- 844.3us, error 0.4%
(kms_flip:2586) DEBUG: name = flip
last_ts = 150.379105
last_received_ts = 150.378525
last_seq = 4649
current_ts = 150.547302
current_received_ts = 150.546661
current_seq = 4659
count = 25
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 150.379105/4649, current 150.547302/4659: elapsed=168193.0us expected=168853.7us +- 844.3us, error 0.4%
(kms_flip:2586) DEBUG: name = vblank
last_ts = 150.530487
last_received_ts = 150.529831
last_seq = 4658
current_ts = 150.698593
current_received_ts = 150.697998
current_seq = 4668
count = 25
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 150.530487/4658, current 150.698593/4668: elapsed=168105.0us expected=168853.7us +- 844.3us, error 0.4%
(kms_flip:2586) DEBUG: name = flip
last_ts = 150.547302
last_received_ts = 150.546661
last_seq = 4659
current_ts = 150.715408
current_received_ts = 150.714767
current_seq = 4669
count = 26
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 150.547302/4659, current 150.715408/4669: elapsed=168110.0us expected=168853.7us +- 844.3us, error 0.4%
(kms_flip:2586) DEBUG: name = vblank
last_ts = 150.698593
last_received_ts = 150.697998
last_seq = 4668
current_ts = 150.866669
current_received_ts = 150.866104
current_seq = 4678
count = 26
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 150.698593/4668, current 150.866669/4678: elapsed=168075.0us expected=168853.7us +- 844.3us, error 0.5%
(kms_flip:2586) DEBUG: name = flip
last_ts = 150.715408
last_received_ts = 150.714767
last_seq = 4669
current_ts = 150.883484
current_received_ts = 150.882858
current_seq = 4679
count = 27
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 150.715408/4669, current 150.883484/4679: elapsed=168067.0us expected=168853.7us +- 844.3us, error 0.5%
(kms_flip:2586) DEBUG: name = vblank
last_ts = 150.866669
last_received_ts = 150.866104
last_seq = 4678
current_ts = 151.034760
current_received_ts = 151.034058
current_seq = 4688
count = 27
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 150.866669/4678, current 151.034760/4688: elapsed=168087.0us expected=168853.7us +- 844.3us, error 0.5%
(kms_flip:2586) DEBUG: name = flip
last_ts = 150.883484
last_received_ts = 150.882858
last_seq = 4679
current_ts = 151.051559
current_received_ts = 151.050964
current_seq = 4689
count = 28
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 150.883484/4679, current 151.051559/4689: elapsed=168073.0us expected=168853.7us +- 844.3us, error 0.5%
(kms_flip:2586) DEBUG: name = vblank
last_ts = 151.034760
last_received_ts = 151.034058
last_seq = 4688
current_ts = 151.202820
current_received_ts = 151.202118
current_seq = 4698
count = 28
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 151.034760/4688, current 151.202820/4698: elapsed=168064.0us expected=168853.7us +- 844.3us, error 0.5%
(kms_flip:2586) DEBUG: name = flip
last_ts = 151.051559
last_received_ts = 151.050964
last_seq = 4689
current_ts = 151.219620
current_received_ts = 151.219025
current_seq = 4699
count = 29
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 151.051559/4689, current 151.219620/4699: elapsed=168073.0us expected=168853.7us +- 844.3us, error 0.5%
(kms_flip:2586) DEBUG: name = vblank
last_ts = 151.202820
last_received_ts = 151.202118
last_seq = 4698
current_ts = 151.370895
current_received_ts = 151.370239
current_seq = 4708
count = 29
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 151.202820/4698, current 151.370895/4708: elapsed=168070.0us expected=168853.7us +- 844.3us, error 0.5%
(kms_flip:2586) DEBUG: name = flip
last_ts = 151.219620
last_received_ts = 151.219025
last_seq = 4699
current_ts = 151.387695
current_received_ts = 151.387177
current_seq = 4709
count = 30
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 151.219620/4699, current 151.387695/4709: elapsed=168075.0us expected=168853.7us +- 844.3us, error 0.5%
(kms_flip:2586) DEBUG: name = vblank
last_ts = 151.370895
last_received_ts = 151.370239
last_seq = 4708
current_ts = 151.538971
current_received_ts = 151.538376
current_seq = 4718
count = 30
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 151.370895/4708, current 151.538971/4718: elapsed=168074.0us expected=168853.7us +- 844.3us, error 0.5%
(kms_flip:2586) DEBUG: name = flip
last_ts = 151.387695
last_received_ts = 151.387177
last_seq = 4709
current_ts = 151.555786
current_received_ts = 151.555130
current_seq = 4719
count = 31
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 151.387695/4709, current 151.555786/4719: elapsed=168078.0us expected=168853.7us +- 844.3us, error 0.5%
(kms_flip:2586) DEBUG: name = vblank
last_ts = 151.538971
last_received_ts = 151.538376
last_seq = 4718
current_ts = 151.706970
current_received_ts = 151.706360
current_seq = 4728
count = 31
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 151.538971/4718, current 151.706970/4728: elapsed=168007.0us expected=168853.7us +- 844.3us, error 0.5%
(kms_flip:2586) DEBUG: inconsistent vblank ts/seq: last 151.538971/4718, current 151.706970/4728: elapsed=168007.0us expected=168853.7us
(kms_flip:2586) DEBUG: name = flip
last_ts = 151.555786
last_received_ts = 151.555130
last_seq = 4719
current_ts = 151.723785
current_received_ts = 151.723129
current_seq = 4729
count = 32
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 151.555786/4719, current 151.723785/4729: elapsed=168002.0us expected=168853.7us +- 844.3us, error 0.5%
(kms_flip:2586) DEBUG: inconsistent flip ts/seq: last 151.555786/4719, current 151.723785/4729: elapsed=168002.0us expected=168853.7us
(kms_flip:2586) DEBUG: name = vblank
last_ts = 151.706970
last_received_ts = 151.706360
last_seq = 4728
current_ts = 151.874939
current_received_ts = 151.874298
current_seq = 4738
count = 32
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 151.706970/4728, current 151.874939/4738: elapsed=167968.0us expected=168853.7us +- 844.3us, error 0.5%
(kms_flip:2586) DEBUG: inconsistent vblank ts/seq: last 151.706970/4728, current 151.874939/4738: elapsed=167968.0us expected=168853.7us
(kms_flip:2586) DEBUG: name = flip
last_ts = 151.723785
last_received_ts = 151.723129
last_seq = 4729
current_ts = 151.891754
current_received_ts = 151.891052
current_seq = 4739
count = 33
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 151.723785/4729, current 151.891754/4739: elapsed=167976.0us expected=168853.7us +- 844.3us, error 0.5%
(kms_flip:2586) DEBUG: inconsistent flip ts/seq: last 151.723785/4729, current 151.891754/4739: elapsed=167976.0us expected=168853.7us
(kms_flip:2586) DEBUG: name = vblank
last_ts = 151.874939
last_received_ts = 151.874298
last_seq = 4738
current_ts = 152.042923
current_received_ts = 152.042328
current_seq = 4748
count = 33
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 151.874939/4738, current 152.042923/4748: elapsed=167972.0us expected=168853.7us +- 844.3us, error 0.5%
(kms_flip:2586) DEBUG: inconsistent vblank ts/seq: last 151.874939/4738, current 152.042923/4748: elapsed=167972.0us expected=168853.7us
(kms_flip:2586) DEBUG: name = flip
last_ts = 151.891754
last_received_ts = 151.891052
last_seq = 4739
current_ts = 152.059708
current_received_ts = 152.059113
current_seq = 4749
count = 34
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 151.891754/4739, current 152.059708/4749: elapsed=167955.0us expected=168853.7us +- 844.3us, error 0.5%
(kms_flip:2586) DEBUG: inconsistent flip ts/seq: last 151.891754/4739, current 152.059708/4749: elapsed=167955.0us expected=168853.7us
(kms_flip:2586) DEBUG: name = vblank
last_ts = 152.042923
last_received_ts = 152.042328
last_seq = 4748
current_ts = 152.210876
current_received_ts = 152.210251
current_seq = 4758
count = 34
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 152.042923/4748, current 152.210876/4758: elapsed=167968.0us expected=168853.7us +- 844.3us, error 0.5%
(kms_flip:2586) DEBUG: inconsistent vblank ts/seq: last 152.042923/4748, current 152.210876/4758: elapsed=167968.0us expected=168853.7us
(kms_flip:2586) DEBUG: name = flip
last_ts = 152.059708
last_received_ts = 152.059113
last_seq = 4749
current_ts = 152.227676
current_received_ts = 152.227097
current_seq = 4759
count = 35
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 152.059708/4749, current 152.227676/4759: elapsed=167970.0us expected=168853.7us +- 844.3us, error 0.5%
(kms_flip:2586) DEBUG: inconsistent flip ts/seq: last 152.059708/4749, current 152.227676/4759: elapsed=167970.0us expected=168853.7us
(kms_flip:2586) DEBUG: name = vblank
last_ts = 152.210876
last_received_ts = 152.210251
last_seq = 4758
current_ts = 152.378876
current_received_ts = 152.378250
current_seq = 4768
count = 35
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 152.210876/4758, current 152.378876/4768: elapsed=167988.0us expected=168853.7us +- 844.3us, error 0.5%
(kms_flip:2586) DEBUG: inconsistent vblank ts/seq: last 152.210876/4758, current 152.378876/4768: elapsed=167988.0us expected=168853.7us
(kms_flip:2586) DEBUG: name = flip
last_ts = 152.227676
last_received_ts = 152.227097
last_seq = 4759
current_ts = 152.395660
current_received_ts = 152.395081
current_seq = 4769
count = 36
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 152.227676/4759, current 152.395660/4769: elapsed=167971.0us expected=168853.7us +- 844.3us, error 0.5%
(kms_flip:2586) DEBUG: inconsistent flip ts/seq: last 152.227676/4759, current 152.395660/4769: elapsed=167971.0us expected=168853.7us
(kms_flip:2586) DEBUG: name = vblank
last_ts = 152.378876
last_received_ts = 152.378250
last_seq = 4768
current_ts = 152.546829
current_received_ts = 152.546173
current_seq = 4778
count = 36
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 152.378876/4768, current 152.546829/4778: elapsed=167952.0us expected=168853.7us +- 844.3us, error 0.5%
(kms_flip:2586) DEBUG: inconsistent vblank ts/seq: last 152.378876/4768, current 152.546829/4778: elapsed=167952.0us expected=168853.7us
(kms_flip:2586) DEBUG: name = flip
last_ts = 152.395660
last_received_ts = 152.395081
last_seq = 4769
current_ts = 152.563629
current_received_ts = 152.562973
current_seq = 4779
count = 37
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 152.395660/4769, current 152.563629/4779: elapsed=167981.0us expected=168853.7us +- 844.3us, error 0.5%
(kms_flip:2586) DEBUG: inconsistent flip ts/seq: last 152.395660/4769, current 152.563629/4779: elapsed=167981.0us expected=168853.7us
(kms_flip:2586) DEBUG: name = vblank
last_ts = 152.546829
last_received_ts = 152.546173
last_seq = 4778
current_ts = 152.714752
current_received_ts = 152.714127
current_seq = 4788
count = 37
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 152.546829/4778, current 152.714752/4788: elapsed=167928.0us expected=168853.7us +- 844.3us, error 0.5%
(kms_flip:2586) DEBUG: inconsistent vblank ts/seq: last 152.546829/4778, current 152.714752/4788: elapsed=167928.0us expected=168853.7us
(kms_flip:2586) DEBUG: name = flip
last_ts = 152.563629
last_received_ts = 152.562973
last_seq = 4779
current_ts = 152.731537
current_received_ts = 152.730927
current_seq = 4789
count = 38
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 152.563629/4779, current 152.731537/4789: elapsed=167901.0us expected=168853.7us +- 844.3us, error 0.6%
(kms_flip:2586) DEBUG: inconsistent flip ts/seq: last 152.563629/4779, current 152.731537/4789: elapsed=167901.0us expected=168853.7us
(kms_flip:2586) DEBUG: name = vblank
last_ts = 152.714752
last_received_ts = 152.714127
last_seq = 4788
current_ts = 152.882614
current_received_ts = 152.882034
current_seq = 4798
count = 38
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 152.714752/4788, current 152.882614/4798: elapsed=167864.0us expected=168853.7us +- 844.3us, error 0.6%
(kms_flip:2586) DEBUG: inconsistent vblank ts/seq: last 152.714752/4788, current 152.882614/4798: elapsed=167864.0us expected=168853.7us
(kms_flip:2586) DEBUG: name = flip
last_ts = 152.731537
last_received_ts = 152.730927
last_seq = 4789
current_ts = 152.899414
current_received_ts = 152.898758
current_seq = 4799
count = 39
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 152.731537/4789, current 152.899414/4799: elapsed=167884.0us expected=168853.7us +- 844.3us, error 0.6%
(kms_flip:2586) DEBUG: inconsistent flip ts/seq: last 152.731537/4789, current 152.899414/4799: elapsed=167884.0us expected=168853.7us
(kms_flip:2586) DEBUG: name = vblank
last_ts = 152.882614
last_received_ts = 152.882034
last_seq = 4798
current_ts = 153.050491
current_received_ts = 153.049896
current_seq = 4808
count = 39
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 152.882614/4798, current 153.050491/4808: elapsed=167882.0us expected=168853.7us +- 844.3us, error 0.6%
(kms_flip:2586) DEBUG: inconsistent vblank ts/seq: last 152.882614/4798, current 153.050491/4808: elapsed=167882.0us expected=168853.7us
(kms_flip:2586) DEBUG: name = flip
last_ts = 152.899414
last_received_ts = 152.898758
last_seq = 4799
current_ts = 153.067291
current_received_ts = 153.066742
current_seq = 4809
count = 40
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 152.899414/4799, current 153.067291/4809: elapsed=167868.0us expected=168853.7us +- 844.3us, error 0.6%
(kms_flip:2586) DEBUG: inconsistent flip ts/seq: last 152.899414/4799, current 153.067291/4809: elapsed=167868.0us expected=168853.7us
(kms_flip:2586) DEBUG: name = vblank
last_ts = 153.050491
last_received_ts = 153.049896
last_seq = 4808
current_ts = 153.218399
current_received_ts = 153.217789
current_seq = 4818
count = 40
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 153.050491/4808, current 153.218399/4818: elapsed=167896.0us expected=168853.7us +- 844.3us, error 0.6%
(kms_flip:2586) DEBUG: inconsistent vblank ts/seq: last 153.050491/4808, current 153.218399/4818: elapsed=167896.0us expected=168853.7us
(kms_flip:2586) DEBUG: name = flip
last_ts = 153.067291
last_received_ts = 153.066742
last_seq = 4809
current_ts = 153.235168
current_received_ts = 153.234558
current_seq = 4819
count = 41
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 153.067291/4809, current 153.235168/4819: elapsed=167879.0us expected=168853.7us +- 844.3us, error 0.6%
(kms_flip:2586) DEBUG: inconsistent flip ts/seq: last 153.067291/4809, current 153.235168/4819: elapsed=167879.0us expected=168853.7us
(kms_flip:2586) DEBUG: name = vblank
last_ts = 153.218399
last_received_ts = 153.217789
last_seq = 4818
current_ts = 153.386276
current_received_ts = 153.385635
current_seq = 4828
count = 41
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 153.218399/4818, current 153.386276/4828: elapsed=167879.0us expected=168853.7us +- 844.3us, error 0.6%
(kms_flip:2586) DEBUG: inconsistent vblank ts/seq: last 153.218399/4818, current 153.386276/4828: elapsed=167879.0us expected=168853.7us
(kms_flip:2586) DEBUG: name = flip
last_ts = 153.235168
last_received_ts = 153.234558
last_seq = 4819
current_ts = 153.403046
current_received_ts = 153.402405
current_seq = 4829
count = 42
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 153.235168/4819, current 153.403046/4829: elapsed=167884.0us expected=168853.7us +- 844.3us, error 0.6%
(kms_flip:2586) DEBUG: inconsistent flip ts/seq: last 153.235168/4819, current 153.403046/4829: elapsed=167884.0us expected=168853.7us
(kms_flip:2586) DEBUG: name = vblank
last_ts = 153.386276
last_received_ts = 153.385635
last_seq = 4828
current_ts = 153.554138
current_received_ts = 153.553497
current_seq = 4838
count = 42
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 153.386276/4828, current 153.554138/4838: elapsed=167865.0us expected=168853.7us +- 844.3us, error 0.6%
(kms_flip:2586) DEBUG: inconsistent vblank ts/seq: last 153.386276/4828, current 153.554138/4838: elapsed=167865.0us expected=168853.7us
(kms_flip:2586) DEBUG: name = flip
last_ts = 153.403046
last_received_ts = 153.402405
last_seq = 4829
current_ts = 153.570938
current_received_ts = 153.570297
current_seq = 4839
count = 43
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 153.403046/4829, current 153.570938/4839: elapsed=167883.0us expected=168853.7us +- 844.3us, error 0.6%
(kms_flip:2586) DEBUG: inconsistent flip ts/seq: last 153.403046/4829, current 153.570938/4839: elapsed=167883.0us expected=168853.7us
(kms_flip:2586) DEBUG: name = vblank
last_ts = 153.554138
last_received_ts = 153.553497
last_seq = 4838
current_ts = 153.721985
current_received_ts = 153.721405
current_seq = 4848
count = 43
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 153.554138/4838, current 153.721985/4848: elapsed=167841.0us expected=168853.7us +- 844.3us, error 0.6%
(kms_flip:2586) DEBUG: inconsistent vblank ts/seq: last 153.554138/4838, current 153.721985/4848: elapsed=167841.0us expected=168853.7us
(kms_flip:2586) DEBUG: name = flip
last_ts = 153.570938
last_received_ts = 153.570297
last_seq = 4839
current_ts = 153.738739
current_received_ts = 153.738113
current_seq = 4849
count = 44
seq_step = 1
(kms_flip:2586) DEBUG: flip ts/seq: last 153.570938/4839, current 153.738739/4849: elapsed=167808.0us expected=168853.7us +- 844.3us, error 0.6%
(kms_flip:2586) DEBUG: inconsistent flip ts/seq: last 153.570938/4839, current 153.738739/4849: elapsed=167808.0us expected=168853.7us
(kms_flip:2586) DEBUG: name = vblank
last_ts = 153.721985
last_received_ts = 153.721405
last_seq = 4848
current_ts = 153.889771
current_received_ts = 153.889114
current_seq = 4858
count = 44
seq_step = 10
(kms_flip:2586) DEBUG: vblank ts/seq: last 153.721985/4848, current 153.889771/4858: elapsed=167798.0us expected=168853.7us +- 844.3us, error 0.6%
(kms_flip:2586) DEBUG: inconsistent vblank ts/seq: last 153.721985/4848, current 153.889771/4858: elapsed=167798.0us expected=168853.7us
(kms_flip:2586) INFO: Event flip: expected 448, counted 450, passrate = 71.11%, encoder type 1
(kms_flip:2586) DEBUG: dropped frames, expected 448, counted 450, passrate = 71.11%, encoder type 1
(kms_flip:2586) INFO: Event vblank: expected 448, counted 450, passrate = 68.89%, encoder type 1
(kms_flip:2586) DEBUG: dropped frames, expected 448, counted 450, passrate = 68.89%, encoder type 1
(kms_flip:2586) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1711:
(kms_flip:2586) CRITICAL: Failed assertion: !retried
(kms_flip:2586) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:2586) igt_core-INFO: Stack trace:
(kms_flip:2586) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2586) igt_core-INFO:   #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
(kms_flip:2586) igt_core-INFO:   #2 ../tests/kms_flip.c:1891 run_test()
(kms_flip:2586) igt_core-INFO:   #3 ../tests/kms_flip.c:2162 __igt_unique____real_main2047()
(kms_flip:2586) igt_core-INFO:   #4 ../tests/kms_flip.c:2047 main()
(kms_flip:2586) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2586) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2586) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-VGA1: FAIL (18.872s)
Dmesg
<6> [134.140874] Console: switching to colour dummy device 80x25
<6> [134.140969] [IGT] kms_flip: executing
<7> [134.153306] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [134.154069] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [134.154468] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [134.158057] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:84:DP-1]
<7> [134.158069] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:84:DP-1]
<7> [134.160853] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.163709] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.166570] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.169436] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.172295] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.175175] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.178037] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.180911] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.183782] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.186619] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.189488] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.192361] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.195228] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.198167] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.201060] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.204015] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.206986] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.209882] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.212887] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.215777] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.218702] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.221610] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.224531] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.227474] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.230624] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.233666] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.236796] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.239973] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.243040] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.246182] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.249389] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.252466] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.252949] 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> [134.255670] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.258570] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.261546] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.264712] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.267809] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.271002] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.274166] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.277275] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.280405] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.283542] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.286525] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.289640] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.292810] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.295943] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.299094] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.302147] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.305270] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.308552] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.311619] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.314725] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.317850] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.320934] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.324016] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.327043] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.330099] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.333194] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.336305] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.339384] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.342441] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.345620] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.348726] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.351816] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.352329] 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> [134.352389] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:84:DP-1] disconnected
<7> [134.352816] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:73:VGA-1]
<7> [134.352829] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:73:VGA-1] force=1
<7> [134.353310] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] ironlake hotplug adpa=0x83f40000, result 1
<7> [134.353695] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT detected via hotplug
<7> [134.354265] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [134.354539] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7> [134.354931] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7> [134.355160] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [134.355166] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7> [134.355388] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7> [134.356463] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7> [134.356468] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7> [134.356768] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:73:VGA-1] probed modes:
<7> [134.356774] 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> [134.356779] 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> [134.356784] 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> [134.356789] 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> [134.356793] 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> [134.356812] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:76:HDMI-A-1]
<7> [134.356819] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:76:HDMI-A-1]
<7> [134.381926] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:76:HDMI-A-1] HDMI: DVI dual 0, max TMDS clock 590000 kHz
<7> [134.381933] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:76:HDMI-A-1] HDMI sink does deep color 30.
<7> [134.381937] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:76:HDMI-A-1] HDMI sink does deep color 36.
<7> [134.381941] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:76:HDMI-A-1] HDMI sink does deep color 48.
<7> [134.381945] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:76:HDMI-A-1] Assigning HDMI sink color depth as 16 bpc.
<7> [134.381950] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:76:HDMI-A-1] HDMI sink does YCRCB444 in deep color.
<7> [134.381955] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:76:HDMI-A-1] ELD monitor Mi TV
<7> [134.381960] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:76:HDMI-A-1] HDMI: latency present 0 0, video latency 0 0, audio latency 0 0
<7> [134.381964] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:76:HDMI-A-1] ELD size 36, SAD count 3
<7> [134.382140] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK for addr: 0040 w(1)
<7> [134.382351] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK on first message, retry
<7> [134.382723] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK for addr: 0040 w(1)
<7> [134.383027] i915 0000:00:02.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [134.384277] 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> [134.384290] 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> [134.384299] 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> [134.384308] 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> [134.384317] 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> [134.384325] 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> [134.384333] 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> [134.384342] 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> [134.384350] 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> [134.384358] 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> [134.384367] 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> [134.384375] 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> [134.384383] 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> [134.384392] 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> [134.384400] 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> [134.384408] 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> [134.384416] 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> [134.384424] 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> [134.384433] 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> [134.384446] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:76:HDMI-A-1] probed modes:
<7> [134.384450] 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> [134.384455] 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> [134.384460] 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> [134.384464] 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> [134.384469] 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> [134.384473] 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> [134.384478] 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> [134.384482] 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> [134.384487] 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> [134.384492] 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> [134.384496] 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> [134.384501] 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> [134.384505] 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> [134.384510] 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> [134.384514] 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> [134.384519] 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> [134.384523] 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> [134.384528] 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> [134.384532] 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> [134.384537] 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> [134.384541] 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> [134.384546] 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> [134.384550] 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> [134.384554] 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> [134.384559] 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> [134.384563] 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> [134.385075] 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> [134.385082] 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> [134.385097] 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> [134.385104] 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> [134.385111] 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> [134.385118] 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> [134.385126] 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> [134.385133] 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> [134.385140] 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> [134.385147] 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> [134.385154] 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> [134.385161] 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> [134.385168] 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> [134.385176] 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> [134.385183] 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> [134.385190] 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> [134.385197] 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> [134.385204] 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> [134.385211] 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> [134.385277] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:84:DP-1]
<7> [134.385286] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:84:DP-1]
<7> [134.388200] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.391086] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.393985] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.396875] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.399746] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.402649] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.405549] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.408437] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.411320] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.414186] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.417061] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.419934] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.422805] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.425687] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.428585] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.431459] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.434349] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.437236] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.440149] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.443045] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.445941] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.448812] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.451697] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.454586] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.457477] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.460445] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.463586] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.466580] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.469665] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.472732] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.475820] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.478873] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.479369] 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> [134.482021] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.485050] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.488083] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.491239] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.494326] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.497432] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.500513] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.503603] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.506690] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.509746] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.512861] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.516029] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.519105] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.522187] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.525316] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.528443] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.531527] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.534615] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.537743] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.540859] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.543943] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.547052] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.550167] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.553313] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.556403] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.559455] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.562524] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.565669] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.568810] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.571898] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.574985] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.578111] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f)
<7> [134.578633] 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> [134.578670] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:84:DP-1] disconnected
<6> [134.587484] [IGT] kms_flip: starting subtest flip-vs-absolute-wf_vblank-interruptible
<6> [134.588769] [IGT] kms_flip: starting dynamic subtest A-VGA1
<7> [134.589449] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:91]
<7> [134.589591] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:93]
<7> [134.618928] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:53:pipe A]
<7> [134.619063] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [134.619360] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [134.619638] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [134.619923] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in has_pch_encoder (expected yes, found no)
<7> [134.620155] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in fdi_lanes (expected 1, found 0)
<7> [134.620424] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53: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> [134.620744] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in output_types (expected 0x00000002, found 0x00000000)
<7> [134.621013] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [134.621248] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1024, found 0)
<7> [134.621565] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 1344, found 0)
<7> [134.621828] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1024, found 0)
<7> [134.622069] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 1344, found 0)
<7> [134.622339] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1048, found 0)
<7> [134.622619] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 1184, found 0)
<7> [134.622892] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 768, found 0)
<7> [134.623159] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 768, found 0)
<7> [134.623450] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 771, found 0)
<7> [134.623750] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 777, found 0)
<7> [134.623980] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 806, found 0)
<7> [134.624227] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 806, found 0)
<7> [134.624540] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1024, found 0)
<7> [134.624803] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 1344, found 0)
<7> [134.625079] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1024, found 0)
<7> [134.625356] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 1344, found 0)
<7> [134.625633] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1048, found 0)
<7> [134.625891] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 1184, found 0)
<7> [134.626159] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 768, found 0)
<7> [134.626435] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 768, found 0)
<7> [134.626700] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 771, found 0)
<7> [134.626958] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 777, found 0)
<7> [134.627251] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 806, found 0)
<7> [134.627501] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 806, found 0)
<7> [134.627776] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [134.628019] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [134.628325] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [134.628564] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [134.628829] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 64800, found 0)
<7> [134.629097] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 64800, found 0)
<7> [134.629382] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in port_clock (expected 64800, found 0)
<7> [134.629651] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:53:pipe A] fastset requirement not met, forcing full modeset
<7> [134.629900] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:53:pipe A] releasing PCH DPLL A
<7> [134.630249] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:53:pipe A] min cdclk: 72000 kHz -> 0 kHz
<7> [134.630492] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:53:pipe A] enable: no [modeset]
<7> [134.630764] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:primary A] fb: [NOFB], visible: no
<7> [134.631169] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [134.644581] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found
<7> [134.645094] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found
<7> [134.645653] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found
<7> [134.661133] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PCH DPLL A (active 0x1, on? 1) for [CRTC:53:pipe A]
<7> [134.661649] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PCH DPLL A
<7> [134.662406] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:CRT]
<7> [134.662963] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:75:HDMI D]
<7> [134.663442] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:83:DP D]
<7> [134.663918] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:73:VGA-1]
<7> [134.664505] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:53:pipe A]
<7> [134.665048] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:72:pipe B]
<7> [134.665141] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [134.665392] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [134.665652] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [134.665926] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in has_pch_encoder (expected yes, found no)
<7> [134.666163] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in fdi_lanes (expected 3, found 0)
<7> [134.666400] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] 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> [134.666665] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [134.666939] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [134.667176] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [134.667413] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [134.667668] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [134.667956] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [134.668193] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [134.668429] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [134.668690] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [134.668968] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [134.669204] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [134.669457] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [134.669728] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [134.669964] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [134.670198] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [134.670465] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [134.670751] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [134.670992] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [134.671228] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [134.671515] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [134.671814] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [134.672051] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [134.672287] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [134.672577] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [134.672865] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [134.673101] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [134.673400] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [134.673672] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [134.673909] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [134.674145] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [134.674400] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [134.674680] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [134.674917] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [134.675154] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148800, found 0)
<7> [134.675411] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148800, found 0)
<7> [134.675684] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in port_clock (expected 223200, found 0)
<7> [134.675920] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [134.676181] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [134.676451] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in avi infoframe
<7> [134.676687] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [134.676921] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [134.676924] i915 0000:00:02.0: colorspace: RGB
<7> [134.676926] i915 0000:00:02.0: scan mode: Underscan
<7> [134.676928] i915 0000:00:02.0: colorimetry: No Data
<7> [134.676930] i915 0000:00:02.0: picture aspect: 16:9
<7> [134.676932] i915 0000:00:02.0: active aspect: Same as Picture
<7> [134.676934] i915 0000:00:02.0: itc: No Data
<7> [134.676936] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [134.676938] i915 0000:00:02.0: quantization range: Default
<7> [134.676940] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [134.676942] i915 0000:00:02.0: video code: 16
<7> [134.676944] i915 0000:00:02.0: ycc quantization range: Full
<7> [134.676946] i915 0000:00:02.0: hdmi content type: Graphics
<7> [134.676948] i915 0000:00:02.0: pixel repeat: 0
<7> [134.676950] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [134.676952] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [134.677215] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in spd infoframe
<7> [134.677511] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [134.677747] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [134.677750] i915 0000:00:02.0: vendor: Intel
<7> [134.677752] i915 0000:00:02.0: product: Integrated gfx
<7> [134.677754] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [134.677756] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [134.677991] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:72:pipe B] fastset requirement not met in hdmi infoframe
<7> [134.678252] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [134.678543] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [134.678546] i915 0000:00:02.0: empty frame
<7> [134.678548] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [134.678784] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:72:pipe B] fastset requirement not met, forcing full modeset
<7> [134.679050] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:72:pipe B] releasing PCH DPLL B
<7> [134.679335] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:72:pipe B] min cdclk: 165334 kHz -> 0 kHz
<7> [134.679574] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:72:pipe B] enable: no [modeset]
<7> [134.679809] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:54:primary B] fb: [NOFB], visible: no
<7> [134.680127] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:76:HDMI-A-1][ENCODER:75:HDMI D] Disable audio codec on [CRTC:72:pipe B]
<7> [134.708711] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe B
<7> [134.725536] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found
<7> [134.726074] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found
<7> [134.726729] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found
<7> [134.737423] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PCH DPLL B (active 0x2, on? 1) for [CRTC:72:pipe B]
<7> [134.737970] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PCH DPLL B
<7> [134.738695] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:CRT]
<7> [134.739138] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:75:HDMI D]
<7> [134.739669] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:83:DP D]
<7> [134.740106] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:76:HDMI-A-1]
<7> [134.740674] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:72:pipe B]
<7> [134.741014] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:53:pipe A]
<7> [134.741042] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:73:VGA-1]
<7> [134.741148] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:73:VGA-1] Limiting target display pipe bpp to 24 (EDID bpp 0, max requested bpp 0, max platform bpp 36)
<7> [134.741412] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:53:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [134.741651] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe A, lanes 1
<7> [134.741880] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [134.742117] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [134.742368] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [134.742605] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in has_pch_encoder (expected no, found yes)
<7> [134.742841] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in fdi_lanes (expected 0, found 1)
<7> [134.743077] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53: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> [134.743332] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000002)
<7> [134.743570] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [134.743806] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1024)
<7> [134.744042] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 1344)
<7> [134.744287] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1024)
<7> [134.744523] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 1344)
<7> [134.744759] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1048)
<7> [134.744994] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 1184)
<7> [134.745243] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 768)
<7> [134.745479] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 768)
<7> [134.745715] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 771)
<7> [134.745950] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 777)
<7> [134.746186] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 806)
<7> [134.746444] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 806)
<7> [134.746802] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1024)
<7> [134.747057] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 1344)
<7> [134.747361] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1024)
<7> [134.747694] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 1344)
<7> [134.747924] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1048)
<7> [134.748287] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 1184)
<7> [134.748591] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 768)
<7> [134.748823] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 768)
<7> [134.749187] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 771)
<7> [134.749516] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 777)
<7> [134.749778] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 806)
<7> [134.750008] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 806)
<7> [134.750369] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [134.750672] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [134.750936] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [134.751293] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [134.751545] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 64800)
<7> [134.751851] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 64800)
<7> [134.752160] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in port_clock (expected 0, found 64800)
<7> [134.752420] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:53:pipe A] fastset requirement not met, forcing full modeset
<7> [134.752776] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:53:pipe A] min cdclk: 0 kHz -> 72000 kHz
<7> [134.753066] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:53:pipe A] allocated PCH DPLL A
<7> [134.753347] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:53:pipe A] reserving PCH DPLL A
<7> [134.753651] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:53:pipe A] enable: yes [modeset]
<7> [134.753939] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB
<7> [134.754281] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [134.754530] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [134.754774] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [134.755176] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [134.755473] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [134.755701] 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> [134.755994] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [134.756357] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1
<7> [134.756592] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [134.756969] 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> [134.757221] 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> [134.757479] 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> [134.757854] 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> [134.757878] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [134.758094] 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> [134.758408] 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> [134.758751] 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> [134.758995] 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> [134.759329] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 64800, pixel rate 64800, min cdclk 72000, min voltage level 0
<7> [134.759567] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0
<7> [134.759884] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1024x768+0+0
<7> [134.760208] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [134.760445] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [134.760791] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [134.761102] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [134.761333] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [134.761681] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [134.762014] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [134.762274] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [134.762553] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [134.762866] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [134.763156] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:primary A] fb: [FB:91] 1024x768 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [134.763388] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [134.763716] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0
<7> [134.764916] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [134.765164] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:CRT]
<7> [134.765518] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:75:HDMI D]
<7> [134.765751] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:83:DP D]
<7> [134.766549] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [134.767659] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100
<7> [134.767904] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done.
<7> [134.768796] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x200
<7> [134.769032] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done.
<7> [134.769293] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train done.
<7> [134.769528] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PCH DPLL A (active 0x1, on? 0) for [CRTC:53:pipe A]
<7> [134.769763] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PCH DPLL A
<7> [134.821731] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:73:VGA-1]
<7> [134.822339] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:53:pipe A]
<7> [145.444551] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:53:pipe A]
<7> [145.444771] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [145.445400] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [145.445884] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [145.446258] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in has_pch_encoder (expected yes, found no)
<7> [145.446715] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in fdi_lanes (expected 1, found 0)
<7> [145.447188] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53: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> [145.447628] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in output_types (expected 0x00000002, found 0x00000000)
<7> [145.448031] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [145.448293] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1024, found 0)
<7> [145.448523] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 1344, found 0)
<7> [145.448819] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1024, found 0)
<7> [145.449066] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 1344, found 0)
<7> [145.449295] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1048, found 0)
<7> [145.449525] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 1184, found 0)
<7> [145.449798] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 768, found 0)
<7> [145.450035] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 768, found 0)
<7> [145.450271] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 771, found 0)
<7> [145.450507] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 777, found 0)
<7> [145.450772] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 806, found 0)
<7> [145.451019] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 806, found 0)
<7> [145.451255] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1024, found 0)
<7> [145.451489] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 1344, found 0)
<7> [145.451738] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1024, found 0)
<7> [145.451974] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 1344, found 0)
<7> [145.452209] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1048, found 0)
<7> [145.452445] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 1184, found 0)
<7> [145.452680] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 768, found 0)
<7> [145.452966] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 768, found 0)
<7> [145.453203] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 771, found 0)
<7> [145.453443] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 777, found 0)
<7> [145.453672] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 806, found 0)
<7> [145.453966] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 806, found 0)
<7> [145.454214] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [145.454444] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [145.454673] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [145.454946] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [145.455197] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 64800, found 0)
<7> [145.455426] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 64800, found 0)
<7> [145.455655] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in port_clock (expected 64800, found 0)
<7> [145.455921] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:53:pipe A] fastset requirement not met, forcing full modeset
<7> [145.456164] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:53:pipe A] releasing PCH DPLL A
<7> [145.456417] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:53:pipe A] min cdclk: 72000 kHz -> 0 kHz
<7> [145.456652] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:53:pipe A] enable: no [modeset]
<7> [145.456960] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:primary A] fb: [NOFB], visible: no
<7> [145.457353] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [145.471409] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found
<7> [145.471686] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found
<7> [145.471910] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found
<7> [145.489329] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PCH DPLL A (active 0x1, on? 1) for [CRTC:53:pipe A]
<7> [145.489832] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PCH DPLL A
<7> [145.490534] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:CRT]
<7> [145.491080] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:75:HDMI D]
<7> [145.491585] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:83:DP D]
<7> [145.492069] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:73:VGA-1]
<7> [145.492633] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:53:pipe A]
<7> [145.493018] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:72:pipe B]
<7> [145.493146] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:53:pipe A]
<7> [145.493171] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:73:VGA-1]
<7> [145.493257] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:73:VGA-1] Limiting target display pipe bpp to 24 (EDID bpp 0, max requested bpp 0, max platform bpp 36)
<7> [145.493521] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:53:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [145.493795] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe A, lanes 1
<7> [145.494021] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [145.494252] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [145.494515] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [145.494799] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in has_pch_encoder (expected no, found yes)
<7> [145.495029] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in fdi_lanes (expected 0, found 1)
<7> [145.495259] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53: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> [145.495536] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000002)
<7> [145.495803] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [145.496041] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1024)
<7> [145.496293] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 1344)
<7> [145.496580] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1024)
<7> [145.496811] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 1344)
<7> [145.497042] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1048)
<7> [145.497321] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 1184)
<7> [145.497615] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 768)
<7> [145.497845] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 768)
<7> [145.498085] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 771)
<7> [145.498375] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 777)
<7> [145.498653] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 806)
<7> [145.498893] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 806)
<7> [145.499131] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1024)
<7> [145.499395] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 1344)
<7> [145.499673] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1024)
<7> [145.499932] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 1344)
<7> [145.500168] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1048)
<7> [145.500433] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 1184)
<7> [145.500709] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 768)
<7> [145.500946] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 768)
<7> [145.501182] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 771)
<7> [145.501443] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 777)
<7> [145.501736] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 806)
<7> [145.501979] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 806)
<7> [145.502241] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [145.502479] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [145.502716] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [145.502953] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [145.503189] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 64800)
<7> [145.503461] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 64800)
<7> [145.503713] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:53:pipe A] fastset requirement not met in port_clock (expected 0, found 64800)
<7> [145.503950] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:53:pipe A] fastset requirement not met, forcing full modeset
<7> [145.504223] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:53:pipe A] min cdclk: 0 kHz -> 72000 kHz
<7> [145.504459] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:53:pipe A] allocated PCH DPLL A
<7> [145.504694] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:53:pipe A] reserving PCH DPLL A
<7> [145.504929] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:53:pipe A] enable: yes [modeset]
<7> [145.505164] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB
<7> [145.505425] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [145.505661] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [145.505895] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [145.506130] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [145.506421] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [145.506657] 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> [145.506893] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [145.507128] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1
<7> [145.507376] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [145.507625] 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> [145.507860] 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> [145.508094] 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> [145.508361] 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> [145.508599] 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> [145.508834] 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> [145.509070] 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> [145.509333] 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> [145.509569] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 64800, pixel rate 64800, min cdclk 72000, min voltage level 0
<7> [145.509803] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0
<7> [145.510037] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1024x768+0+0
<7> [145.510285] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [145.510521] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [145.510755] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [145.510989] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [145.511144] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [145.511265] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [145.511545] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [145.511781] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [145.512015] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [145.512270] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [145.512525] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [145.512761] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:primary A] fb: [FB:91] 1024x768 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [145.512998] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [145.513261] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0
<7> [145.513576] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [145.513819] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:CRT]
<7> [145.514049] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:75:HDMI D]
<7> [145.514355] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:83:DP D]
<7> [145.515147] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [145.516233] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100
<7> [145.516461] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done.
<7> [145.517342] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x200
<7> [145.517602] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done.
<7> [145.517840] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train done.
<7> [145.518119] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PCH DPLL A (active 0x1, on? 0) for [CRTC:53:pipe A]
<7> [145.518393] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PCH DPLL A
<7> [145.570387] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:73:VGA-1]
<7> [145.571008] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:53:pipe A]
<6> [153.460953] [IGT] kms_flip: finished subtest A-VGA1, FAIL
Created at 2026-04-03 08:37:12