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