Hardware List

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

Machine description: shard-snb4

Result: Fail

git-log-oneline i915_display_info7 igt_runner7 runtimes7 results7.json results7-i915-load.json i915_display_info_post_exec7 boot7 dmesg7

Detail	Value
Duration	10.38 seconds
Hostname	shard-snb4
Igt-Version	IGT-Version: 1.30-g80f0cbff4 (x86_64) (Linux: 6.14.0-rc6-CI_DRM_16261-g003c44ec0b7d+ x86_64)
Out	Using IGT_SRANDOM=1741672098 for randomisation Opened device: /dev/dri/card0 Using monotonic timestamps Starting subtest: plain-flip-ts-check Starting dynamic subtest: A-VGA1 1024x768: 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa Expected frametime: 16666us; measured 16849.8us +- 3.851us accuracy 0.07% vblank interval differs from modeline! expected 16665.6us, measured 16850us +- 3.851us, difference 184.2us (47.8 sigma) Stack trace: #0 ../lib/igt_core.c:2055 __igt_fail_assert() #1 ../tests/kms_flip.c:777 run_test_on_crtc_set.constprop.0() #2 ../tests/kms_flip.c:1831 run_test() #3 ../tests/kms_flip.c:2064 __igt_unique____real_main1987() #4 ../tests/kms_flip.c:1987 main() #5 [__libc_init_first+0x8a] #6 [__libc_start_main+0x8b] #7 [_start+0x25] Dynamic subtest A-VGA1: FAIL (10.375s)
Err	Starting subtest: plain-flip-ts-check Starting dynamic subtest: A-VGA1 (kms_flip:2788) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1660: (kms_flip:2788) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon) Dynamic subtest A-VGA1 failed. ** DEBUG (kms_flip:2788) DEBUG: flip ts/seq: last 166.824341/5681, current 166.841125/5682: elapsed=16789.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 166.841125 last_received_ts = 166.840530 last_seq = 5682 current_ts = 166.857925 current_received_ts = 166.857330 current_seq = 5683 count = 281 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 166.841125/5682, current 166.857925/5683: elapsed=16791.0us expected=16849.8us +- 84.2us, error 0.3% (kms_flip:2788) DEBUG: name = flip last_ts = 166.857925 last_received_ts = 166.857330 last_seq = 5683 current_ts = 166.874710 current_received_ts = 166.874115 current_seq = 5684 count = 282 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 166.857925/5683, current 166.874710/5684: elapsed=16788.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 166.874710 last_received_ts = 166.874115 last_seq = 5684 current_ts = 166.891495 current_received_ts = 166.890884 current_seq = 5685 count = 283 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 166.874710/5684, current 166.891495/5685: elapsed=16789.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 166.891495 last_received_ts = 166.890884 last_seq = 5685 current_ts = 166.908279 current_received_ts = 166.907700 current_seq = 5686 count = 284 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 166.891495/5685, current 166.908279/5686: elapsed=16790.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 166.908279 last_received_ts = 166.907700 last_seq = 5686 current_ts = 166.925079 current_received_ts = 166.924484 current_seq = 5687 count = 285 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 166.908279/5686, current 166.925079/5687: elapsed=16787.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 166.925079 last_received_ts = 166.924484 last_seq = 5687 current_ts = 166.941864 current_received_ts = 166.941269 current_seq = 5688 count = 286 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 166.925079/5687, current 166.941864/5688: elapsed=16791.0us expected=16849.8us +- 84.2us, error 0.3% (kms_flip:2788) DEBUG: name = flip last_ts = 166.941864 last_received_ts = 166.941269 last_seq = 5688 current_ts = 166.958649 current_received_ts = 166.958084 current_seq = 5689 count = 287 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 166.941864/5688, current 166.958649/5689: elapsed=16788.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 166.958649 last_received_ts = 166.958084 last_seq = 5689 current_ts = 166.975449 current_received_ts = 166.974823 current_seq = 5690 count = 288 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 166.958649/5689, current 166.975449/5690: elapsed=16798.0us expected=16849.8us +- 84.2us, error 0.3% (kms_flip:2788) DEBUG: name = flip last_ts = 166.975449 last_received_ts = 166.974823 last_seq = 5690 current_ts = 166.992233 current_received_ts = 166.991638 current_seq = 5691 count = 289 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 166.975449/5690, current 166.992233/5691: elapsed=16781.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 166.992233 last_received_ts = 166.991638 last_seq = 5691 current_ts = 167.009018 current_received_ts = 167.008362 current_seq = 5692 count = 290 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 166.992233/5691, current 167.009018/5692: elapsed=16784.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.009018 last_received_ts = 167.008362 last_seq = 5692 current_ts = 167.025818 current_received_ts = 167.025223 current_seq = 5693 count = 291 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.009018/5692, current 167.025818/5693: elapsed=16804.0us expected=16849.8us +- 84.2us, error 0.3% (kms_flip:2788) DEBUG: name = flip last_ts = 167.025818 last_received_ts = 167.025223 last_seq = 5693 current_ts = 167.042603 current_received_ts = 167.041946 current_seq = 5694 count = 292 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.025818/5693, current 167.042603/5694: elapsed=16788.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.042603 last_received_ts = 167.041946 last_seq = 5694 current_ts = 167.059387 current_received_ts = 167.058792 current_seq = 5695 count = 293 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.042603/5694, current 167.059387/5695: elapsed=16773.0us expected=16849.8us +- 84.2us, error 0.5% (kms_flip:2788) DEBUG: name = flip last_ts = 167.059387 last_received_ts = 167.058792 last_seq = 5695 current_ts = 167.076172 current_received_ts = 167.075623 current_seq = 5696 count = 294 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.059387/5695, current 167.076172/5696: elapsed=16796.0us expected=16849.8us +- 84.2us, error 0.3% (kms_flip:2788) DEBUG: name = flip last_ts = 167.076172 last_received_ts = 167.075623 last_seq = 5696 current_ts = 167.092957 current_received_ts = 167.092392 current_seq = 5697 count = 295 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.076172/5696, current 167.092957/5697: elapsed=16785.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.092957 last_received_ts = 167.092392 last_seq = 5697 current_ts = 167.109756 current_received_ts = 167.109207 current_seq = 5698 count = 296 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.092957/5697, current 167.109756/5698: elapsed=16792.0us expected=16849.8us +- 84.2us, error 0.3% (kms_flip:2788) DEBUG: name = flip last_ts = 167.109756 last_received_ts = 167.109207 last_seq = 5698 current_ts = 167.126541 current_received_ts = 167.126038 current_seq = 5699 count = 297 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.109756/5698, current 167.126541/5699: elapsed=16790.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.126541 last_received_ts = 167.126038 last_seq = 5699 current_ts = 167.143326 current_received_ts = 167.142746 current_seq = 5700 count = 298 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.126541/5699, current 167.143326/5700: elapsed=16784.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.143326 last_received_ts = 167.142746 last_seq = 5700 current_ts = 167.160126 current_received_ts = 167.159576 current_seq = 5701 count = 299 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.143326/5700, current 167.160126/5701: elapsed=16796.0us expected=16849.8us +- 84.2us, error 0.3% (kms_flip:2788) DEBUG: name = flip last_ts = 167.160126 last_received_ts = 167.159576 last_seq = 5701 current_ts = 167.176910 current_received_ts = 167.176361 current_seq = 5702 count = 300 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.160126/5701, current 167.176910/5702: elapsed=16788.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.176910 last_received_ts = 167.176361 last_seq = 5702 current_ts = 167.193695 current_received_ts = 167.193115 current_seq = 5703 count = 301 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.176910/5702, current 167.193695/5703: elapsed=16783.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.193695 last_received_ts = 167.193115 last_seq = 5703 current_ts = 167.210480 current_received_ts = 167.209930 current_seq = 5704 count = 302 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.193695/5703, current 167.210480/5704: elapsed=16792.0us expected=16849.8us +- 84.2us, error 0.3% (kms_flip:2788) DEBUG: name = flip last_ts = 167.210480 last_received_ts = 167.209930 last_seq = 5704 current_ts = 167.227280 current_received_ts = 167.226715 current_seq = 5705 count = 303 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.210480/5704, current 167.227280/5705: elapsed=16792.0us expected=16849.8us +- 84.2us, error 0.3% (kms_flip:2788) DEBUG: name = flip last_ts = 167.227280 last_received_ts = 167.226715 last_seq = 5705 current_ts = 167.244064 current_received_ts = 167.243484 current_seq = 5706 count = 304 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.227280/5705, current 167.244064/5706: elapsed=16789.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.244064 last_received_ts = 167.243484 last_seq = 5706 current_ts = 167.260849 current_received_ts = 167.260284 current_seq = 5707 count = 305 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.244064/5706, current 167.260849/5707: elapsed=16789.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.260849 last_received_ts = 167.260284 last_seq = 5707 current_ts = 167.277649 current_received_ts = 167.277084 current_seq = 5708 count = 306 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.260849/5707, current 167.277649/5708: elapsed=16787.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.277649 last_received_ts = 167.277084 last_seq = 5708 current_ts = 167.294434 current_received_ts = 167.293854 current_seq = 5709 count = 307 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.277649/5708, current 167.294434/5709: elapsed=16791.0us expected=16849.8us +- 84.2us, error 0.3% (kms_flip:2788) DEBUG: name = flip last_ts = 167.294434 last_received_ts = 167.293854 last_seq = 5709 current_ts = 167.311218 current_received_ts = 167.310638 current_seq = 5710 count = 308 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.294434/5709, current 167.311218/5710: elapsed=16782.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.311218 last_received_ts = 167.310638 last_seq = 5710 current_ts = 167.328003 current_received_ts = 167.327438 current_seq = 5711 count = 309 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.311218/5710, current 167.328003/5711: elapsed=16781.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.328003 last_received_ts = 167.327438 last_seq = 5711 current_ts = 167.344772 current_received_ts = 167.344177 current_seq = 5712 count = 310 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.328003/5711, current 167.344772/5712: elapsed=16782.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.344772 last_received_ts = 167.344177 last_seq = 5712 current_ts = 167.361557 current_received_ts = 167.360992 current_seq = 5713 count = 311 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.344772/5712, current 167.361557/5713: elapsed=16782.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.361557 last_received_ts = 167.360992 last_seq = 5713 current_ts = 167.378342 current_received_ts = 167.377777 current_seq = 5714 count = 312 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.361557/5713, current 167.378342/5714: elapsed=16775.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.378342 last_received_ts = 167.377777 last_seq = 5714 current_ts = 167.395126 current_received_ts = 167.394531 current_seq = 5715 count = 313 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.378342/5714, current 167.395126/5715: elapsed=16786.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.395126 last_received_ts = 167.394531 last_seq = 5715 current_ts = 167.411896 current_received_ts = 167.411331 current_seq = 5716 count = 314 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.395126/5715, current 167.411896/5716: elapsed=16781.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.411896 last_received_ts = 167.411331 last_seq = 5716 current_ts = 167.428680 current_received_ts = 167.428116 current_seq = 5717 count = 315 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.411896/5716, current 167.428680/5717: elapsed=16783.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.428680 last_received_ts = 167.428116 last_seq = 5717 current_ts = 167.445465 current_received_ts = 167.444870 current_seq = 5718 count = 316 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.428680/5717, current 167.445465/5718: elapsed=16778.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.445465 last_received_ts = 167.444870 last_seq = 5718 current_ts = 167.462250 current_received_ts = 167.461655 current_seq = 5719 count = 317 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.445465/5718, current 167.462250/5719: elapsed=16783.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.462250 last_received_ts = 167.461655 last_seq = 5719 current_ts = 167.479034 current_received_ts = 167.478439 current_seq = 5720 count = 318 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.462250/5719, current 167.479034/5720: elapsed=16780.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.479034 last_received_ts = 167.478439 last_seq = 5720 current_ts = 167.495804 current_received_ts = 167.495209 current_seq = 5721 count = 319 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.479034/5720, current 167.495804/5721: elapsed=16782.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.495804 last_received_ts = 167.495209 last_seq = 5721 current_ts = 167.512589 current_received_ts = 167.511993 current_seq = 5722 count = 320 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.495804/5721, current 167.512589/5722: elapsed=16772.0us expected=16849.8us +- 84.2us, error 0.5% (kms_flip:2788) DEBUG: name = flip last_ts = 167.512589 last_received_ts = 167.511993 last_seq = 5722 current_ts = 167.529388 current_received_ts = 167.528793 current_seq = 5723 count = 321 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.512589/5722, current 167.529388/5723: elapsed=16801.0us expected=16849.8us +- 84.2us, error 0.3% (kms_flip:2788) DEBUG: name = flip last_ts = 167.529388 last_received_ts = 167.528793 last_seq = 5723 current_ts = 167.546158 current_received_ts = 167.545563 current_seq = 5724 count = 322 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.529388/5723, current 167.546158/5724: elapsed=16771.0us expected=16849.8us +- 84.2us, error 0.5% (kms_flip:2788) DEBUG: name = flip last_ts = 167.546158 last_received_ts = 167.545563 last_seq = 5724 current_ts = 167.562927 current_received_ts = 167.562363 current_seq = 5725 count = 323 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.546158/5724, current 167.562927/5725: elapsed=16780.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.562927 last_received_ts = 167.562363 last_seq = 5725 current_ts = 167.579712 current_received_ts = 167.579147 current_seq = 5726 count = 324 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.562927/5725, current 167.579712/5726: elapsed=16776.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.579712 last_received_ts = 167.579147 last_seq = 5726 current_ts = 167.596497 current_received_ts = 167.595932 current_seq = 5727 count = 325 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.579712/5726, current 167.596497/5727: elapsed=16786.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.596497 last_received_ts = 167.595932 last_seq = 5727 current_ts = 167.613266 current_received_ts = 167.612717 current_seq = 5728 count = 326 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.596497/5727, current 167.613266/5728: elapsed=16778.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.613266 last_received_ts = 167.612717 last_seq = 5728 current_ts = 167.630051 current_received_ts = 167.629501 current_seq = 5729 count = 327 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.613266/5728, current 167.630051/5729: elapsed=16784.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.630051 last_received_ts = 167.629501 last_seq = 5729 current_ts = 167.646835 current_received_ts = 167.646271 current_seq = 5730 count = 328 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.630051/5729, current 167.646835/5730: elapsed=16781.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.646835 last_received_ts = 167.646271 last_seq = 5730 current_ts = 167.663620 current_received_ts = 167.663071 current_seq = 5731 count = 329 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.646835/5730, current 167.663620/5731: elapsed=16782.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.663620 last_received_ts = 167.663071 last_seq = 5731 current_ts = 167.680405 current_received_ts = 167.679825 current_seq = 5732 count = 330 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.663620/5731, current 167.680405/5732: elapsed=16781.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.680405 last_received_ts = 167.679825 last_seq = 5732 current_ts = 167.697189 current_received_ts = 167.696594 current_seq = 5733 count = 331 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.680405/5732, current 167.697189/5733: elapsed=16781.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.697189 last_received_ts = 167.696594 last_seq = 5733 current_ts = 167.713959 current_received_ts = 167.713379 current_seq = 5734 count = 332 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.697189/5733, current 167.713959/5734: elapsed=16781.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.713959 last_received_ts = 167.713379 last_seq = 5734 current_ts = 167.730743 current_received_ts = 167.730164 current_seq = 5735 count = 333 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.713959/5734, current 167.730743/5735: elapsed=16777.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.730743 last_received_ts = 167.730164 last_seq = 5735 current_ts = 167.747528 current_received_ts = 167.746948 current_seq = 5736 count = 334 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.730743/5735, current 167.747528/5736: elapsed=16786.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.747528 last_received_ts = 167.746948 last_seq = 5736 current_ts = 167.764313 current_received_ts = 167.763718 current_seq = 5737 count = 335 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.747528/5736, current 167.764313/5737: elapsed=16782.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.764313 last_received_ts = 167.763718 last_seq = 5737 current_ts = 167.781082 current_received_ts = 167.780533 current_seq = 5738 count = 336 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.764313/5737, current 167.781082/5738: elapsed=16781.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.781082 last_received_ts = 167.780533 last_seq = 5738 current_ts = 167.797867 current_received_ts = 167.797302 current_seq = 5739 count = 337 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.781082/5738, current 167.797867/5739: elapsed=16782.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.797867 last_received_ts = 167.797302 last_seq = 5739 current_ts = 167.814651 current_received_ts = 167.814117 current_seq = 5740 count = 338 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.797867/5739, current 167.814651/5740: elapsed=16779.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.814651 last_received_ts = 167.814117 last_seq = 5740 current_ts = 167.831436 current_received_ts = 167.830841 current_seq = 5741 count = 339 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.814651/5740, current 167.831436/5741: elapsed=16790.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.831436 last_received_ts = 167.830841 last_seq = 5741 current_ts = 167.848206 current_received_ts = 167.847656 current_seq = 5742 count = 340 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.831436/5741, current 167.848206/5742: elapsed=16773.0us expected=16849.8us +- 84.2us, error 0.5% (kms_flip:2788) DEBUG: name = flip last_ts = 167.848206 last_received_ts = 167.847656 last_seq = 5742 current_ts = 167.864990 current_received_ts = 167.864426 current_seq = 5743 count = 341 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.848206/5742, current 167.864990/5743: elapsed=16781.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.864990 last_received_ts = 167.864426 last_seq = 5743 current_ts = 167.881775 current_received_ts = 167.881195 current_seq = 5744 count = 342 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.864990/5743, current 167.881775/5744: elapsed=16782.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.881775 last_received_ts = 167.881195 last_seq = 5744 current_ts = 167.898560 current_received_ts = 167.897995 current_seq = 5745 count = 343 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.881775/5744, current 167.898560/5745: elapsed=16780.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.898560 last_received_ts = 167.897995 last_seq = 5745 current_ts = 167.915329 current_received_ts = 167.914749 current_seq = 5746 count = 344 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.898560/5745, current 167.915329/5746: elapsed=16776.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.915329 last_received_ts = 167.914749 last_seq = 5746 current_ts = 167.932114 current_received_ts = 167.931534 current_seq = 5747 count = 345 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.915329/5746, current 167.932114/5747: elapsed=16786.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.932114 last_received_ts = 167.931534 last_seq = 5747 current_ts = 167.948898 current_received_ts = 167.948318 current_seq = 5748 count = 346 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.932114/5747, current 167.948898/5748: elapsed=16781.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.948898 last_received_ts = 167.948318 last_seq = 5748 current_ts = 167.965668 current_received_ts = 167.965103 current_seq = 5749 count = 347 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.948898/5748, current 167.965668/5749: elapsed=16774.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.965668 last_received_ts = 167.965103 last_seq = 5749 current_ts = 167.982468 current_received_ts = 167.981903 current_seq = 5750 count = 348 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.965668/5749, current 167.982468/5750: elapsed=16788.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.982468 last_received_ts = 167.981903 last_seq = 5750 current_ts = 167.999237 current_received_ts = 167.998672 current_seq = 5751 count = 349 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.982468/5750, current 167.999237/5751: elapsed=16779.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 167.999237 last_received_ts = 167.998672 last_seq = 5751 current_ts = 168.016022 current_received_ts = 168.015457 current_seq = 5752 count = 350 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 167.999237/5751, current 168.016022/5752: elapsed=16784.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.016022 last_received_ts = 168.015457 last_seq = 5752 current_ts = 168.032806 current_received_ts = 168.032242 current_seq = 5753 count = 351 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.016022/5752, current 168.032806/5753: elapsed=16781.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.032806 last_received_ts = 168.032242 last_seq = 5753 current_ts = 168.049591 current_received_ts = 168.049042 current_seq = 5754 count = 352 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.032806/5753, current 168.049591/5754: elapsed=16781.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.049591 last_received_ts = 168.049042 last_seq = 5754 current_ts = 168.066360 current_received_ts = 168.065720 current_seq = 5755 count = 353 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.049591/5754, current 168.066360/5755: elapsed=16772.0us expected=16849.8us +- 84.2us, error 0.5% (kms_flip:2788) DEBUG: name = flip last_ts = 168.066360 last_received_ts = 168.065720 last_seq = 5755 current_ts = 168.083145 current_received_ts = 168.082565 current_seq = 5756 count = 354 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.066360/5755, current 168.083145/5756: elapsed=16791.0us expected=16849.8us +- 84.2us, error 0.3% (kms_flip:2788) DEBUG: name = flip last_ts = 168.083145 last_received_ts = 168.082565 last_seq = 5756 current_ts = 168.099930 current_received_ts = 168.099365 current_seq = 5757 count = 355 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.083145/5756, current 168.099930/5757: elapsed=16776.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.099930 last_received_ts = 168.099365 last_seq = 5757 current_ts = 168.116714 current_received_ts = 168.116104 current_seq = 5758 count = 356 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.099930/5757, current 168.116714/5758: elapsed=16793.0us expected=16849.8us +- 84.2us, error 0.3% (kms_flip:2788) DEBUG: name = flip last_ts = 168.116714 last_received_ts = 168.116104 last_seq = 5758 current_ts = 168.133499 current_received_ts = 168.132935 current_seq = 5759 count = 357 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.116714/5758, current 168.133499/5759: elapsed=16774.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.133499 last_received_ts = 168.132935 last_seq = 5759 current_ts = 168.150269 current_received_ts = 168.149719 current_seq = 5760 count = 358 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.133499/5759, current 168.150269/5760: elapsed=16781.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.150269 last_received_ts = 168.149719 last_seq = 5760 current_ts = 168.167053 current_received_ts = 168.166489 current_seq = 5761 count = 359 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.150269/5760, current 168.167053/5761: elapsed=16783.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.167053 last_received_ts = 168.166489 last_seq = 5761 current_ts = 168.183838 current_received_ts = 168.183273 current_seq = 5762 count = 360 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.167053/5761, current 168.183838/5762: elapsed=16779.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.183838 last_received_ts = 168.183273 last_seq = 5762 current_ts = 168.200607 current_received_ts = 168.200073 current_seq = 5763 count = 361 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.183838/5762, current 168.200607/5763: elapsed=16776.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.200607 last_received_ts = 168.200073 last_seq = 5763 current_ts = 168.217392 current_received_ts = 168.216827 current_seq = 5764 count = 362 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.200607/5763, current 168.217392/5764: elapsed=16786.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.217392 last_received_ts = 168.216827 last_seq = 5764 current_ts = 168.234177 current_received_ts = 168.233612 current_seq = 5765 count = 363 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.217392/5764, current 168.234177/5765: elapsed=16781.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.234177 last_received_ts = 168.233612 last_seq = 5765 current_ts = 168.250961 current_received_ts = 168.250412 current_seq = 5766 count = 364 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.234177/5765, current 168.250961/5766: elapsed=16781.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.250961 last_received_ts = 168.250412 last_seq = 5766 current_ts = 168.267746 current_received_ts = 168.267166 current_seq = 5767 count = 365 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.250961/5766, current 168.267746/5767: elapsed=16781.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.267746 last_received_ts = 168.267166 last_seq = 5767 current_ts = 168.284515 current_received_ts = 168.283936 current_seq = 5768 count = 366 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.267746/5767, current 168.284515/5768: elapsed=16779.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.284515 last_received_ts = 168.283936 last_seq = 5768 current_ts = 168.301300 current_received_ts = 168.300735 current_seq = 5769 count = 367 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.284515/5768, current 168.301300/5769: elapsed=16783.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.301300 last_received_ts = 168.300735 last_seq = 5769 current_ts = 168.318069 current_received_ts = 168.317490 current_seq = 5770 count = 368 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.301300/5769, current 168.318069/5770: elapsed=16773.0us expected=16849.8us +- 84.2us, error 0.5% (kms_flip:2788) DEBUG: name = flip last_ts = 168.318069 last_received_ts = 168.317490 last_seq = 5770 current_ts = 168.334854 current_received_ts = 168.334290 current_seq = 5771 count = 369 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.318069/5770, current 168.334854/5771: elapsed=16774.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.334854 last_received_ts = 168.334290 last_seq = 5771 current_ts = 168.351624 current_received_ts = 168.351074 current_seq = 5772 count = 370 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.334854/5771, current 168.351624/5772: elapsed=16776.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.351624 last_received_ts = 168.351074 last_seq = 5772 current_ts = 168.368393 current_received_ts = 168.367844 current_seq = 5773 count = 371 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.351624/5772, current 168.368393/5773: elapsed=16772.0us expected=16849.8us +- 84.2us, error 0.5% (kms_flip:2788) DEBUG: name = flip last_ts = 168.368393 last_received_ts = 168.367844 last_seq = 5773 current_ts = 168.385178 current_received_ts = 168.384613 current_seq = 5774 count = 372 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.368393/5773, current 168.385178/5774: elapsed=16775.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.385178 last_received_ts = 168.384613 last_seq = 5774 current_ts = 168.401947 current_received_ts = 168.401382 current_seq = 5775 count = 373 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.385178/5774, current 168.401947/5775: elapsed=16774.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.401947 last_received_ts = 168.401382 last_seq = 5775 current_ts = 168.418716 current_received_ts = 168.418152 current_seq = 5776 count = 374 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.401947/5775, current 168.418716/5776: elapsed=16775.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.418716 last_received_ts = 168.418152 last_seq = 5776 current_ts = 168.435501 current_received_ts = 168.434937 current_seq = 5777 count = 375 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.418716/5776, current 168.435501/5777: elapsed=16773.0us expected=16849.8us +- 84.2us, error 0.5% (kms_flip:2788) DEBUG: name = flip last_ts = 168.435501 last_received_ts = 168.434937 last_seq = 5777 current_ts = 168.452271 current_received_ts = 168.451706 current_seq = 5778 count = 376 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.435501/5777, current 168.452271/5778: elapsed=16774.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.452271 last_received_ts = 168.451706 last_seq = 5778 current_ts = 168.469040 current_received_ts = 168.468460 current_seq = 5779 count = 377 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.452271/5778, current 168.469040/5779: elapsed=16775.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.469040 last_received_ts = 168.468460 last_seq = 5779 current_ts = 168.485825 current_received_ts = 168.485229 current_seq = 5780 count = 378 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.469040/5779, current 168.485825/5780: elapsed=16774.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.485825 last_received_ts = 168.485229 last_seq = 5780 current_ts = 168.502594 current_received_ts = 168.502045 current_seq = 5781 count = 379 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.485825/5780, current 168.502594/5781: elapsed=16771.0us expected=16849.8us +- 84.2us, error 0.5% (kms_flip:2788) DEBUG: name = flip last_ts = 168.502594 last_received_ts = 168.502045 last_seq = 5781 current_ts = 168.519363 current_received_ts = 168.518784 current_seq = 5782 count = 380 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.502594/5781, current 168.519363/5782: elapsed=16777.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.519363 last_received_ts = 168.518784 last_seq = 5782 current_ts = 168.536133 current_received_ts = 168.535583 current_seq = 5783 count = 381 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.519363/5782, current 168.536133/5783: elapsed=16774.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.536133 last_received_ts = 168.535583 last_seq = 5783 current_ts = 168.552917 current_received_ts = 168.552338 current_seq = 5784 count = 382 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.536133/5783, current 168.552917/5784: elapsed=16774.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.552917 last_received_ts = 168.552338 last_seq = 5784 current_ts = 168.569687 current_received_ts = 168.569122 current_seq = 5785 count = 383 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.552917/5784, current 168.569687/5785: elapsed=16775.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.569687 last_received_ts = 168.569122 last_seq = 5785 current_ts = 168.586456 current_received_ts = 168.585907 current_seq = 5786 count = 384 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.569687/5785, current 168.586456/5786: elapsed=16774.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.586456 last_received_ts = 168.585907 last_seq = 5786 current_ts = 168.603241 current_received_ts = 168.602661 current_seq = 5787 count = 385 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.586456/5786, current 168.603241/5787: elapsed=16774.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.603241 last_received_ts = 168.602661 last_seq = 5787 current_ts = 168.620010 current_received_ts = 168.619431 current_seq = 5788 count = 386 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.603241/5787, current 168.620010/5788: elapsed=16774.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.620010 last_received_ts = 168.619431 last_seq = 5788 current_ts = 168.636780 current_received_ts = 168.636169 current_seq = 5789 count = 387 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.620010/5788, current 168.636780/5789: elapsed=16766.0us expected=16849.8us +- 84.2us, error 0.5% (kms_flip:2788) DEBUG: name = flip last_ts = 168.636780 last_received_ts = 168.636169 last_seq = 5789 current_ts = 168.653564 current_received_ts = 168.652985 current_seq = 5790 count = 388 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.636780/5789, current 168.653564/5790: elapsed=16783.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.653564 last_received_ts = 168.652985 last_seq = 5790 current_ts = 168.670334 current_received_ts = 168.669769 current_seq = 5791 count = 389 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.653564/5790, current 168.670334/5791: elapsed=16768.0us expected=16849.8us +- 84.2us, error 0.5% (kms_flip:2788) DEBUG: name = flip last_ts = 168.670334 last_received_ts = 168.669769 last_seq = 5791 current_ts = 168.687103 current_received_ts = 168.686539 current_seq = 5792 count = 390 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.670334/5791, current 168.687103/5792: elapsed=16780.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.687103 last_received_ts = 168.686539 last_seq = 5792 current_ts = 168.703888 current_received_ts = 168.703293 current_seq = 5793 count = 391 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.687103/5792, current 168.703888/5793: elapsed=16774.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.703888 last_received_ts = 168.703293 last_seq = 5793 current_ts = 168.720657 current_received_ts = 168.720093 current_seq = 5794 count = 392 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.703888/5793, current 168.720657/5794: elapsed=16776.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.720657 last_received_ts = 168.720093 last_seq = 5794 current_ts = 168.737427 current_received_ts = 168.736862 current_seq = 5795 count = 393 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.720657/5794, current 168.737427/5795: elapsed=16772.0us expected=16849.8us +- 84.2us, error 0.5% (kms_flip:2788) DEBUG: name = flip last_ts = 168.737427 last_received_ts = 168.736862 last_seq = 5795 current_ts = 168.754196 current_received_ts = 168.753616 current_seq = 5796 count = 394 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.737427/5795, current 168.754196/5796: elapsed=16774.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.754196 last_received_ts = 168.753616 last_seq = 5796 current_ts = 168.770981 current_received_ts = 168.770401 current_seq = 5797 count = 395 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.754196/5796, current 168.770981/5797: elapsed=16775.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.770981 last_received_ts = 168.770401 last_seq = 5797 current_ts = 168.787750 current_received_ts = 168.787186 current_seq = 5798 count = 396 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.770981/5797, current 168.787750/5798: elapsed=16773.0us expected=16849.8us +- 84.2us, error 0.5% (kms_flip:2788) DEBUG: name = flip last_ts = 168.787750 last_received_ts = 168.787186 last_seq = 5798 current_ts = 168.804520 current_received_ts = 168.803925 current_seq = 5799 count = 397 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.787750/5798, current 168.804520/5799: elapsed=16769.0us expected=16849.8us +- 84.2us, error 0.5% (kms_flip:2788) DEBUG: name = flip last_ts = 168.804520 last_received_ts = 168.803925 last_seq = 5799 current_ts = 168.821304 current_received_ts = 168.820663 current_seq = 5800 count = 398 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.804520/5799, current 168.821304/5800: elapsed=16790.0us expected=16849.8us +- 84.2us, error 0.4% (kms_flip:2788) DEBUG: name = flip last_ts = 168.821304 last_received_ts = 168.820663 last_seq = 5800 current_ts = 168.838074 current_received_ts = 168.837494 current_seq = 5801 count = 399 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.821304/5800, current 168.838074/5801: elapsed=16770.0us expected=16849.8us +- 84.2us, error 0.5% (kms_flip:2788) DEBUG: name = flip last_ts = 168.838074 last_received_ts = 168.837494 last_seq = 5801 current_ts = 168.854843 current_received_ts = 168.854218 current_seq = 5802 count = 400 seq_step = 1 (kms_flip:2788) DEBUG: flip ts/seq: last 168.838074/5801, current 168.854843/5802: elapsed=16760.0us expected=16849.8us +- 84.2us, error 0.5% (kms_flip:2788) DEBUG: inconsistent flip ts/seq: last 168.838074/5801, current 168.854843/5802: elapsed=16760.0us expected=16849.8us (kms_flip:2788) DEBUG: expected 0, counted 400, encoder type 1 (kms_flip:2788) DEBUG: dropped frames, expected 0, counted 400, encoder type 1 (kms_flip:2788) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1660: (kms_flip:2788) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon) (kms_flip:2788) igt_core-INFO: Stack trace: (kms_flip:2788) igt_core-INFO: #0 ../lib/igt_core.c:2055 __igt_fail_assert() (kms_flip:2788) igt_core-INFO: #1 ../tests/kms_flip.c:777 run_test_on_crtc_set.constprop.0() (kms_flip:2788) igt_core-INFO: #2 ../tests/kms_flip.c:1831 run_test() (kms_flip:2788) igt_core-INFO: #3 ../tests/kms_flip.c:2064 __igt_unique____real_main1987() (kms_flip:2788) igt_core-INFO: #4 ../tests/kms_flip.c:1987 main() (kms_flip:2788) igt_core-INFO: #5 [__libc_init_first+0x8a] (kms_flip:2788) igt_core-INFO: #6 [__libc_start_main+0x8b] (kms_flip:2788) igt_core-INFO: #7 [_start+0x25] END ** Dynamic subtest A-VGA1: FAIL (10.375s)
Dmesg	<6> [160.998430] Console: switching to colour dummy device 80x25 <6> [160.999065] [IGT] kms_flip: executing <7> [161.006814] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <7> [161.008177] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc] <7> [161.008738] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <7> [161.013226] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1] <7> [161.013256] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:64:VGA-1] force=1 <7> [161.013548] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] ironlake hotplug adpa=0xf40000, result 0 <7> [161.013808] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT not detected via hotplug <7> [161.014292] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1) <7> [161.014512] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry <7> [161.014928] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1) <7> [161.015196] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga <7> [161.015202] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging <7> [161.015495] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1 <7> [161.017030] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga <7> [161.017039] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0 <7> [161.017355] i915 0000:00:02.0: [drm:intel_crt_detect_ddc [i915]] CRT not detected via DDC:0x50 [no valid EDID found] <7> [161.017671] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1] disconnected <7> [161.018433] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:75:DP-1] <7> [161.018462] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:75:DP-1] <7> [161.022505] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.026462] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.030454] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.034402] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.038368] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.042359] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.046303] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.050290] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.054264] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.058235] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.062203] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.066153] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.070122] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.074150] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.078332] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.082485] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.086529] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.090692] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.094706] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.098892] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.102879] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.106858] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.110809] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.114923] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.119008] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.122994] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.126943] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.130907] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.134763] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.138566] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.142579] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.146785] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.147128] 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> [161.147170] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:75:DP-1] disconnected <7> [161.147563] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1] <7> [161.147590] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:64:VGA-1] force=1 <7> [161.147972] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] ironlake hotplug adpa=0xf40000, result 0 <7> [161.148435] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT not detected via hotplug <7> [161.149046] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1) <7> [161.149382] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry <7> [161.149843] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1) <7> [161.150176] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga <7> [161.150188] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging <7> [161.150499] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1 <7> [161.151857] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga <7> [161.151863] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0 <7> [161.152069] i915 0000:00:02.0: [drm:intel_crt_detect_ddc [i915]] CRT not detected via DDC:0x50 [no valid EDID found] <7> [161.152276] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1] disconnected <7> [161.152512] i915 0000:00:02.0: [drm:status_store] [CONNECTOR:64:VGA-1] force updated from 0 to 2 or reprobing <7> [161.152521] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1] <7> [161.152536] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1] status updated from disconnected to connected <7> [161.152588] i915 0000:00:02.0: [drm:drm_sysfs_hotplug_event] generating hotplug event <7> [161.152681] i915 0000:00:02.0: [drm:drm_client_hotplug] fbdev: ret=0 <7> [161.152722] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1) <7> [161.153053] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry <7> [161.153571] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1) <7> [161.153810] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga <7> [161.153822] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging <7> [161.154032] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1 <7> [161.155448] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga <7> [161.155460] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0 <7> [161.155779] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1] probed modes: <7> [161.155799] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa <7> [161.155822] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5 <7> [161.155837] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 56 36000 800 824 896 1024 600 601 603 625 0x40 0x5 <7> [161.155852] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "848x480": 60 33750 848 864 976 1088 480 486 494 517 0x40 0x5 <7> [161.155865] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa <7> [161.155924] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1] <7> [161.156140] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1) <7> [161.156450] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry <7> [161.156997] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1) <7> [161.157306] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga <7> [161.157320] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging <7> [161.157596] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1 <7> [161.158917] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga <7> [161.158933] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0 <7> [161.159357] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:64:VGA-1] probed modes: <7> [161.159385] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa <7> [161.159412] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5 <7> [161.159438] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 56 36000 800 824 896 1024 600 601 603 625 0x40 0x5 <7> [161.159462] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "848x480": 60 33750 848 864 976 1088 480 486 494 517 0x40 0x5 <7> [161.159486] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa <7> [161.159535] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:67:HDMI-A-1] <7> [161.159562] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:67:HDMI-A-1] <7> [161.185561] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] HDMI: DVI dual 0, max TMDS clock 590000 kHz <7> [161.185575] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] HDMI sink does deep color 30. <7> [161.185581] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] HDMI sink does deep color 36. <7> [161.185587] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] HDMI sink does deep color 48. <7> [161.185592] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] Assigning HDMI sink color depth as 16 bpc. <7> [161.185597] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] HDMI sink does YCRCB444 in deep color. <7> [161.185605] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] ELD monitor Mi TV <7> [161.185612] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] HDMI: latency present 0 0, video latency 0 0, audio latency 0 0 <7> [161.185618] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:67:HDMI-A-1] ELD size 36, SAD count 3 <7> [161.185854] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK for addr: 0040 w(1) <7> [161.186176] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK on first message, retry <7> [161.186626] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK for addr: 0040 w(1) <7> [161.187071] i915 0000:00:02.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6) <7> [161.189150] 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> [161.189167] 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> [161.189180] 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> [161.189191] 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> [161.189203] 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> [161.189214] 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> [161.189225] 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> [161.189236] 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> [161.189247] 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> [161.189265] 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> [161.189273] 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> [161.189281] 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> [161.189289] 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> [161.189298] 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> [161.189306] 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> [161.189314] 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> [161.189322] 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> [161.189330] 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> [161.189338] 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> [161.189352] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:67:HDMI-A-1] probed modes: <7> [161.189383] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 <7> [161.189418] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "2880x1800": 30 167000 2880 2910 2920 3030 1800 1820 1828 1836 0x40 0x5 <7> [161.189466] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1200": 60 154000 1920 1968 2000 2080 1200 1203 1209 1235 0x40 0x9 <7> [161.189499] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5 <7> [161.189525] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080": 60 148352 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5 <7> [161.189549] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080i": 60 74250 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x15 <7> [161.189574] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080i": 60 74250 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x20015 <7> [161.189598] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080i": 60 74176 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x15 <7> [161.189622] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080i": 60 74176 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x20015 <7> [161.189647] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080": 50 148500 1920 2448 2492 2640 1080 1084 1089 1125 0x40 0x5 <7> [161.189670] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080i": 50 74250 1920 2448 2492 2640 1080 1084 1094 1125 0x40 0x15 <7> [161.189694] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080i": 50 74250 1920 2448 2492 2640 1080 1084 1094 1125 0x40 0x20015 <7> [161.189718] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080": 30 74250 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5 <7> [161.189742] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080": 30 74176 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5 <7> [161.189766] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080": 25 74250 1920 2448 2492 2640 1080 1084 1089 1125 0x40 0x5 <7> [161.189789] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080": 24 74250 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x5 <7> [161.189813] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080": 24 74250 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x1c005 <7> [161.189837] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080": 24 74250 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x4005 <7> [161.189861] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080": 24 74176 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x5 <7> [161.189885] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080": 24 74176 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x1c005 <7> [161.189908] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080": 24 74176 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x4005 <7> [161.189932] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1600x1200": 60 162000 1600 1664 1856 2160 1200 1201 1204 1250 0x40 0x5 <7> [161.189956] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1680x1050": 60 119000 1680 1728 1760 1840 1050 1053 1059 1080 0x40 0x9 <7> [161.189980] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1600x900": 60 108000 1600 1624 1704 1800 900 901 904 1000 0x40 0x5 <7> [161.190004] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1280x1024": 60 108000 1280 1328 1440 1688 1024 1025 1028 1066 0x40 0x5 <7> [161.190027] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1440x900": 60 88750 1440 1488 1520 1600 900 903 909 926 0x40 0x9 <7> [161.190051] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1366x768": 60 85500 1366 1436 1579 1792 768 771 774 798 0x40 0x5 <7> [161.190075] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1280x800": 60 71000 1280 1328 1360 1440 800 803 809 823 0x40 0x9 <7> [161.190099] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1280x720": 60 74250 1280 1390 1430 1650 720 725 730 750 0x40 0x5 <7> [161.190123] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1280x720": 60 74250 1280 1390 1430 1650 720 725 730 750 0x40 0x1c005 <7> [161.190146] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1280x720": 60 74250 1280 1390 1430 1650 720 725 730 750 0x40 0x4005 <7> [161.190170] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1280x720": 60 74176 1280 1390 1430 1650 720 725 730 750 0x40 0x5 <7> [161.190194] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1280x720": 60 74176 1280 1390 1430 1650 720 725 730 750 0x40 0x1c005 <7> [161.190218] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1280x720": 60 74176 1280 1390 1430 1650 720 725 730 750 0x40 0x4005 <7> [161.190241] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1280x720": 50 74250 1280 1720 1760 1980 720 725 730 750 0x40 0x5 <7> [161.190265] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1280x720": 50 74250 1280 1720 1760 1980 720 725 730 750 0x40 0x1c005 <7> [161.190289] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1280x720": 50 74250 1280 1720 1760 1980 720 725 730 750 0x40 0x4005 <7> [161.190313] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa <7> [161.190336] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5 <7> [161.190360] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "720x576": 50 27000 720 732 796 864 576 581 586 625 0x40 0xa <7> [161.190384] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "720x576i": 50 13500 720 732 795 864 576 580 586 625 0x40 0x101a <7> [161.190410] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "720x480": 60 27027 720 736 798 858 480 489 495 525 0x40 0xa <7> [161.190447] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "720x480": 60 27000 720 736 798 858 480 489 495 525 0x40 0xa <7> [161.190473] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "640x480": 60 25200 640 656 752 800 480 490 492 525 0x40 0xa <7> [161.190499] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa <7> [161.190614] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:75:DP-1] <7> [161.190642] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:75:DP-1] <7> [161.194582] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.198368] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.202223] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.206023] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.209918] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.213682] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.217552] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.221186] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.225026] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.228858] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.232755] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.236490] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.240380] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.244008] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.247685] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.251577] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.255358] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.259214] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.262856] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.266716] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.270803] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.274764] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.278739] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.282895] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.286924] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.291115] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.295128] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.299288] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.303303] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.307260] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.311439] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.315372] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DP D: timeout (status 0x7143003f) <7> [161.315754] 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> [161.315803] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:75:DP-1] disconnected <6> [161.317763] [IGT] kms_flip: starting subtest plain-flip-ts-check <6> [161.319525] [IGT] kms_flip: starting dynamic subtest A-VGA1 <7> [161.320000] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:79] <7> [161.320093] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:83] <7> [161.370247] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:47:pipe A] <7> [161.370453] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.enable (expected yes, found no) <7> [161.370934] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.active (expected yes, found no) <7> [161.371408] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1) <7> [161.371849] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in has_pch_encoder (expected yes, found no) <7> [161.372335] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in fdi_lanes (expected 3, found 0) <7> [161.372772] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47: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> [161.373239] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in lane_count (expected 4, found 0) <7> [161.373677] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000) <7> [161.374079] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0) <7> [161.374445] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0) <7> [161.374832] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0) <7> [161.375235] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0) <7> [161.375593] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0) <7> [161.375992] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0) <7> [161.376242] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0) <7> [161.376452] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0) <7> [161.376663] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0) <7> [161.376873] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0) <7> [161.377093] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0) <7> [161.377303] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0) <7> [161.377513] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0) <7> [161.377723] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0) <7> [161.377942] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0) <7> [161.378152] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0) <7> [161.378363] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0) <7> [161.378573] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0) <7> [161.378783] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0) <7> [161.379001] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0) <7> [161.379212] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0) <7> [161.379421] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0) <7> [161.379632] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0) <7> [161.379842] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0) <7> [161.380060] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0) <7> [161.380270] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0) <7> [161.380481] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0) <7> [161.380691] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0) <7> [161.380908] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in has_hdmi_sink (expected yes, found no) <7> [161.381120] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in has_infoframe (expected yes, found no) <7> [161.381331] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in pipe_bpp (expected 36, found 0) <7> [161.381544] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148800, found 0) <7> [161.381749] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148800, found 0) <7> [161.381981] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in port_clock (expected 223200, found 0) <7> [161.382203] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000) <7> [161.382407] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000) <7> [161.382612] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in avi infoframe <7> [161.382816] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected: <7> [161.383061] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13 <7> [161.383072] i915 0000:00:02.0: colorspace: RGB <7> [161.383074] i915 0000:00:02.0: scan mode: Underscan <7> [161.383077] i915 0000:00:02.0: colorimetry: No Data <7> [161.383079] i915 0000:00:02.0: picture aspect: 16:9 <7> [161.383081] i915 0000:00:02.0: active aspect: Same as Picture <7> [161.383083] i915 0000:00:02.0: itc: No Data <7> [161.383086] i915 0000:00:02.0: extended colorimetry: xvYCC 601 <7> [161.383088] i915 0000:00:02.0: quantization range: Default <7> [161.383090] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling <7> [161.383092] i915 0000:00:02.0: video code: 16 <7> [161.383094] i915 0000:00:02.0: ycc quantization range: Full <7> [161.383096] i915 0000:00:02.0: hdmi content type: Graphics <7> [161.383098] i915 0000:00:02.0: pixel repeat: 0 <7> [161.383101] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0 <7> [161.383103] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found: <7> [161.383427] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in spd infoframe <7> [161.383642] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected: <7> [161.383856] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25 <7> [161.383859] i915 0000:00:02.0: vendor: Intel <7> [161.383861] i915 0000:00:02.0: product: Integrated gfx <7> [161.383863] i915 0000:00:02.0: source device information: PC General (0x9) <7> [161.383866] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found: <7> [161.384084] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hdmi infoframe <7> [161.384295] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected: <7> [161.384504] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4 <7> [161.384507] i915 0000:00:02.0: empty frame <7> [161.384509] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found: <7> [161.384719] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:47:pipe A] fastset requirement not met, forcing full modeset <7> [161.384939] i915 0000:00:02.0: [drm:intel_unreference_shared_dpll_crtc [i915]] [CRTC:47:pipe A] releasing PCH DPLL A <7> [161.385213] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 400000 kHz, actual 400000 kHz <7> [161.385434] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0 <7> [161.385651] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:47:pipe A] enable: no [modeset] <7> [161.385881] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:primary A] fb: [NOFB], visible: no <7> [161.386128] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:67:HDMI-A-1][ENCODER:66:HDMI D] Disable audio codec on [CRTC:47:pipe A] <7> [161.419292] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port D <7> [161.420168] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A <7> [161.437153] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found <7> [161.437817] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found <7> [161.438251] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found <7> [161.448703] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disable PCH DPLL A (active 0x1, on? 1) for [CRTC:47:pipe A] <7> [161.449168] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disabling PCH DPLL A <7> [161.449857] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:65:CRT] <7> [161.450286] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:66:HDMI D] <7> [161.450780] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:DP D] <7> [161.451249] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:67:HDMI-A-1] <7> [161.451794] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:47:pipe A] <7> [161.452129] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:63:pipe B] <7> [161.452273] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:47:pipe A] <7> [161.452300] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:64:VGA-1] <7> [161.452406] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:64:VGA-1] Limiting display bpp to 24 (EDID bpp 0, max requested bpp 0, max platform bpp 36) <7> [161.452638] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:47:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0 <7> [161.452862] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe A, lanes 1 <7> [161.453123] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.enable (expected no, found yes) <7> [161.453367] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.active (expected no, found yes) <7> [161.453586] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0) <7> [161.453797] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in has_pch_encoder (expected no, found yes) <7> [161.454008] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in fdi_lanes (expected 0, found 1) <7> [161.454234] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47: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> [161.454541] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000002) <7> [161.454761] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1) <7> [161.454979] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1024) <7> [161.455190] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 1344) <7> [161.455416] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1024) <7> [161.455635] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 1344) <7> [161.455843] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1048) <7> [161.456104] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 1184) <7> [161.456373] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 768) <7> [161.456610] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 768) <7> [161.456816] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 771) <7> [161.457020] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 777) <7> [161.457224] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 806) <7> [161.457476] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 806) <7> [161.457729] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1024) <7> [161.457941] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 1344) <7> [161.458155] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1024) <7> [161.458395] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 1344) <7> [161.458620] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1048) <7> [161.458825] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 1184) <7> [161.459072] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 768) <7> [161.459332] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 768) <7> [161.459570] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 771) <7> [161.459774] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 777) <7> [161.459979] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 806) <7> [161.460184] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 806) <7> [161.460440] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1) <7> [161.460689] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2) <7> [161.460927] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8) <7> [161.461156] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in dpll_hw_state <7> [161.461385] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected: <7> [161.461610] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0xc4010001, dpll_md: 0x0, fp0: 0x30f06, fp1: 0x30f06 <7> [161.461815] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found: <7> [161.462057] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09 <7> [161.462332] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24) <7> [161.462554] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 64800) <7> [161.462759] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 64800) <7> [161.462964] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in port_clock (expected 0, found 64800) <7> [161.463171] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:47:pipe A] fastset requirement not met, forcing full modeset <7> [161.463487] i915 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [i915]] [PLANE:32:primary A] min cdclk (64800 kHz) > [CRTC:47:pipe A] min cdclk (0 kHz) <7> [161.463736] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 400000 kHz, actual 400000 kHz <7> [161.463952] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0 <7> [161.464159] i915 0000:00:02.0: [drm:intel_find_shared_dpll [i915]] [CRTC:47:pipe A] allocated PCH DPLL A <7> [161.464406] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc [i915]] [CRTC:47:pipe A] reserving PCH DPLL A <7> [161.464624] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:47:pipe A] enable: yes [modeset] <7> [161.464829] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB <7> [161.465070] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0 <7> [161.465335] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid> <7> [161.465561] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0 <7> [161.465779] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0 <7> [161.465983] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0 <7> [161.466187] 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> [161.466423] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0 <7> [161.466701] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1 <7> [161.466931] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vblank delay: 0, framestart delay: 1, MSA timing delay: 0 <7> [161.467151] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0 <7> [161.467396] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: -1, vmax vblank: -2, vmin vtotal: 1, vmax vtotal: 0 <7> [161.467621] 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> [161.467826] 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> [161.468055] 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> [161.468335] 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> [161.468551] 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> [161.468757] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 64800, pipe src: 1024x768+0+0, pixel rate 64800 <7> [161.468962] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0 <7> [161.469166] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no <7> [161.469411] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0 <7> [161.469431] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on <7> [161.469628] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09 <7> [161.469832] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0 <7> [161.470094] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries <7> [161.470378] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000 <7> [161.470596] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [161.470801] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [161.471005] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [161.471236] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:primary A] fb: [FB:79] 1024x768 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes <7> [161.471462] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0 <7> [161.471705] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0 <7> [161.472829] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on <7> [161.473108] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:65:CRT] <7> [161.473351] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:66:HDMI D] <7> [161.473577] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:DP D] <7> [161.474341] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A <7> [161.475382] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100 <7> [161.475586] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done. <7> [161.476459] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x200 <7> [161.476662] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done. <7> [161.476870] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train done. <7> [161.477096] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enable PCH DPLL A (active 0x1, on? 0) for [CRTC:47:pipe A] <7> [161.477323] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling PCH DPLL A <7> [161.529317] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:64:VGA-1] <7> [161.529865] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:47:pipe A] <6> [171.695779] [IGT] kms_flip: finished subtest A-VGA1, FAIL

Created at 2025-03-11 07:07:43