Result:
integration-manifest git-log-oneline i915_display_info22 igt_runner22 runtimes22 results22.json results22-i915-load.json guc_logs22.tar i915_display_info_post_exec22 boot22 dmesg22
| Detail | Value |
|---|---|
| Duration | 15.79 seconds |
| Hostname |
shard-rkl-4 |
| Igt-Version |
IGT-Version: 2.4-gaf4230819 (x86_64) (Linux: 7.1.0-rc4-CI_DRM_18513-g94e4b8dc66d1+ x86_64) |
| Out |
Using IGT_SRANDOM=1779200073 for randomisation Opened device: /dev/dri/card0 Using monotonic timestamps Starting subtest: flip-vs-expired-vblank-interruptible Starting dynamic subtest: A-HDMI-A2 1920x1080: 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5 Stack trace: #0 ../lib/igt_core.c:2074 __igt_fail_assert() #1 ../tests/kms_flip.c:926 run_test_on_crtc_set() #2 ../tests/kms_flip.c:1920 run_test() #3 ../tests/kms_flip.c:2220 __igt_unique____real_main2105() #4 ../tests/kms_flip.c:2105 main() #5 [__libc_init_first+0x8a] #6 [__libc_start_main+0x8b] #7 [_start+0x25] Dynamic subtest A-HDMI-A2: FAIL (4.073s) Starting dynamic subtest: B-HDMI-A2 1920x1080: 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5 Dynamic subtest B-HDMI-A2: SUCCESS (10.171s) Starting dynamic subtest: C-HDMI-A2 1920x1080: 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5 Stack trace: #0 ../lib/igt_core.c:2074 __igt_fail_assert() #1 ../tests/kms_flip.c:926 run_test_on_crtc_set() #2 ../tests/kms_flip.c:1920 run_test() #3 ../tests/kms_flip.c:2220 __igt_unique____real_main2105() #4 ../tests/kms_flip.c:2105 main() #5 [__libc_init_first+0x8a] #6 [__libc_start_main+0x8b] #7 [_start+0x25] Dynamic subtest C-HDMI-A2: FAIL (1.539s) Subtest flip-vs-expired-vblank-interruptible: FAIL (15.789s) Stack trace: #0 ../lib/igt_core.c:2074 __igt_fail_assert() #1 ../lib/igt_kms.c:2352 kmstest_set_connector_dpms() #2 ../lib/igt_core.c:2960 igt_atexit_handler() #3 [erand48_r+0x1d6] #4 [exit+0x1e] #5 ../lib/igt_core.c:2415 igt_exit() #6 [main+0x3f] #7 [__libc_init_first+0x8a] #8 [__libc_start_main+0x8b] #9 [_start+0x25] |
| Err |
Starting subtest: flip-vs-expired-vblank-interruptible Starting dynamic subtest: A-HDMI-A2 [65.984389] (kms_flip:2123) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931: [65.984450] (kms_flip:2123) CRITICAL: Failed assertion: end - start < 500 [65.984493] (kms_flip:2123) CRITICAL: Last errno: 4, Interrupted system call Dynamic subtest A-HDMI-A2 failed. **** DEBUG **** [63.687841] (kms_flip:2123) DEBUG: Vblank took 33us [63.706696] (kms_flip:2123) DEBUG: name = flip last_ts = 63.473347 last_received_ts = 63.472786 last_seq = 546 current_ts = 63.492233 current_received_ts = 63.491646 current_seq = 547 count = 79 seq_step = 1 [63.706746] (kms_flip:2123) DEBUG: Vblank took 36us [63.725670] (kms_flip:2123) DEBUG: name = flip last_ts = 63.492233 last_received_ts = 63.491646 last_seq = 547 current_ts = 63.511131 current_received_ts = 63.510536 current_seq = 548 count = 80 seq_step = 1 [63.725720] (kms_flip:2123) DEBUG: Vblank took 35us [63.744603] (kms_flip:2123) DEBUG: name = flip last_ts = 63.511131 last_received_ts = 63.510536 last_seq = 548 current_ts = 63.530025 current_received_ts = 63.529400 current_seq = 549 count = 81 seq_step = 1 [63.744642] (kms_flip:2123) DEBUG: Vblank took 29us [63.763668] (kms_flip:2123) DEBUG: name = flip last_ts = 63.530025 last_received_ts = 63.529400 last_seq = 549 current_ts = 63.548920 current_received_ts = 63.548378 current_seq = 550 count = 82 seq_step = 1 [63.763715] (kms_flip:2123) DEBUG: Vblank took 33us [63.782624] (kms_flip:2123) DEBUG: name = flip last_ts = 63.548920 last_received_ts = 63.548378 last_seq = 550 current_ts = 63.567806 current_received_ts = 63.567253 current_seq = 551 count = 83 seq_step = 1 [63.782671] (kms_flip:2123) DEBUG: Vblank took 33us [63.801611] (kms_flip:2123) DEBUG: name = flip last_ts = 63.567806 last_received_ts = 63.567253 last_seq = 551 current_ts = 63.586693 current_received_ts = 63.586159 current_seq = 552 count = 84 seq_step = 1 [63.801658] (kms_flip:2123) DEBUG: Vblank took 33us [63.820559] (kms_flip:2123) DEBUG: name = flip last_ts = 63.586693 last_received_ts = 63.586159 last_seq = 552 current_ts = 63.605598 current_received_ts = 63.605026 current_seq = 553 count = 85 seq_step = 1 [63.820606] (kms_flip:2123) DEBUG: Vblank took 33us [63.839449] (kms_flip:2123) DEBUG: name = flip last_ts = 63.605598 last_received_ts = 63.605026 last_seq = 553 current_ts = 63.624481 current_received_ts = 63.623844 current_seq = 554 count = 86 seq_step = 1 [63.839487] (kms_flip:2123) DEBUG: Vblank took 29us [63.858501] (kms_flip:2123) DEBUG: name = flip last_ts = 63.624481 last_received_ts = 63.623844 last_seq = 554 current_ts = 63.643387 current_received_ts = 63.642803 current_seq = 555 count = 87 seq_step = 1 [63.858550] (kms_flip:2123) DEBUG: Vblank took 35us [63.877459] (kms_flip:2123) DEBUG: name = flip last_ts = 63.643387 last_received_ts = 63.642803 last_seq = 555 current_ts = 63.662262 current_received_ts = 63.661682 current_seq = 556 count = 88 seq_step = 1 [63.877506] (kms_flip:2123) DEBUG: Vblank took 33us [63.896417] (kms_flip:2123) DEBUG: name = flip last_ts = 63.662262 last_received_ts = 63.661682 last_seq = 556 current_ts = 63.681168 current_received_ts = 63.680565 current_seq = 557 count = 89 seq_step = 1 [63.896458] (kms_flip:2123) DEBUG: Vblank took 30us [63.915376] (kms_flip:2123) DEBUG: name = flip last_ts = 63.681168 last_received_ts = 63.680565 last_seq = 557 current_ts = 63.700066 current_received_ts = 63.699444 current_seq = 558 count = 90 seq_step = 1 [63.915419] (kms_flip:2123) DEBUG: Vblank took 31us [63.934365] (kms_flip:2123) DEBUG: name = flip last_ts = 63.700066 last_received_ts = 63.699444 last_seq = 558 current_ts = 63.718956 current_received_ts = 63.718349 current_seq = 559 count = 91 seq_step = 1 [63.934412] (kms_flip:2123) DEBUG: Vblank took 32us [63.953376] (kms_flip:2123) DEBUG: name = flip last_ts = 63.718956 last_received_ts = 63.718349 last_seq = 559 current_ts = 63.737846 current_received_ts = 63.737278 current_seq = 560 count = 92 seq_step = 1 [63.953423] (kms_flip:2123) DEBUG: Vblank took 33us [63.972381] (kms_flip:2123) DEBUG: name = flip last_ts = 63.737846 last_received_ts = 63.737278 last_seq = 560 current_ts = 63.756729 current_received_ts = 63.756203 current_seq = 561 count = 93 seq_step = 1 [63.972429] (kms_flip:2123) DEBUG: Vblank took 33us [63.991349] (kms_flip:2123) DEBUG: name = flip last_ts = 63.756729 last_received_ts = 63.756203 last_seq = 561 current_ts = 63.775623 current_received_ts = 63.775085 current_seq = 562 count = 94 seq_step = 1 [63.991399] (kms_flip:2123) DEBUG: Vblank took 36us [64.010350] (kms_flip:2123) DEBUG: name = flip last_ts = 63.775623 last_received_ts = 63.775085 last_seq = 562 current_ts = 63.794521 current_received_ts = 63.793983 current_seq = 563 count = 95 seq_step = 1 [64.010402] (kms_flip:2123) DEBUG: Vblank took 37us [64.029216] (kms_flip:2123) DEBUG: name = flip last_ts = 63.794521 last_received_ts = 63.793983 last_seq = 563 current_ts = 63.813419 current_received_ts = 63.812794 current_seq = 564 count = 96 seq_step = 1 [64.029267] (kms_flip:2123) DEBUG: Vblank took 36us [64.048223] (kms_flip:2123) DEBUG: name = flip last_ts = 63.813419 last_received_ts = 63.812794 last_seq = 564 current_ts = 63.832310 current_received_ts = 63.831718 current_seq = 565 count = 97 seq_step = 1 [64.048274] (kms_flip:2123) DEBUG: Vblank took 36us [64.067252] (kms_flip:2123) DEBUG: name = flip last_ts = 63.832310 last_received_ts = 63.831718 last_seq = 565 current_ts = 63.851204 current_received_ts = 63.850677 current_seq = 566 count = 98 seq_step = 1 [64.067291] (kms_flip:2123) DEBUG: Vblank took 29us [64.086106] (kms_flip:2123) DEBUG: name = flip last_ts = 63.851204 last_received_ts = 63.850677 last_seq = 566 current_ts = 63.870094 current_received_ts = 63.869453 current_seq = 567 count = 99 seq_step = 1 [64.086145] (kms_flip:2123) DEBUG: Vblank took 29us [64.105190] (kms_flip:2123) DEBUG: name = flip last_ts = 63.870094 last_received_ts = 63.869453 last_seq = 567 current_ts = 63.888988 current_received_ts = 63.888447 current_seq = 568 count = 100 seq_step = 1 [64.105240] (kms_flip:2123) DEBUG: Vblank took 35us [64.124179] (kms_flip:2123) DEBUG: name = flip last_ts = 63.888988 last_received_ts = 63.888447 last_seq = 568 current_ts = 63.907871 current_received_ts = 63.907352 current_seq = 569 count = 101 seq_step = 1 [64.124230] (kms_flip:2123) DEBUG: Vblank took 36us [64.143090] (kms_flip:2123) DEBUG: name = flip last_ts = 63.907871 last_received_ts = 63.907352 last_seq = 569 current_ts = 63.926769 current_received_ts = 63.926186 current_seq = 570 count = 102 seq_step = 1 [64.143140] (kms_flip:2123) DEBUG: Vblank took 35us [64.162028] (kms_flip:2123) DEBUG: name = flip last_ts = 63.926769 last_received_ts = 63.926186 last_seq = 570 current_ts = 63.945663 current_received_ts = 63.945049 current_seq = 571 count = 103 seq_step = 1 [64.162076] (kms_flip:2123) DEBUG: Vblank took 38us [64.181175] (kms_flip:2123) DEBUG: name = flip last_ts = 63.945663 last_received_ts = 63.945049 last_seq = 571 current_ts = 63.964554 current_received_ts = 63.964108 current_seq = 572 count = 104 seq_step = 1 [64.181226] (kms_flip:2123) DEBUG: Vblank took 37us [64.200016] (kms_flip:2123) DEBUG: name = flip last_ts = 63.964554 last_received_ts = 63.964108 last_seq = 572 current_ts = 63.983459 current_received_ts = 63.982868 current_seq = 573 count = 105 seq_step = 1 [64.200065] (kms_flip:2123) DEBUG: Vblank took 36us [64.218984] (kms_flip:2123) DEBUG: name = flip last_ts = 63.983459 last_received_ts = 63.982868 last_seq = 573 current_ts = 64.002350 current_received_ts = 64.001755 current_seq = 574 count = 106 seq_step = 1 [64.219030] (kms_flip:2123) DEBUG: Vblank took 34us [64.238004] (kms_flip:2123) DEBUG: name = flip last_ts = 64.002350 last_received_ts = 64.001755 last_seq = 574 current_ts = 64.021248 current_received_ts = 64.020691 current_seq = 575 count = 107 seq_step = 1 [64.238058] (kms_flip:2123) DEBUG: Vblank took 38us [64.256920] (kms_flip:2123) DEBUG: name = flip last_ts = 64.021248 last_received_ts = 64.020691 last_seq = 575 current_ts = 64.040131 current_received_ts = 64.039536 current_seq = 576 count = 108 seq_step = 1 [64.256966] (kms_flip:2123) DEBUG: Vblank took 32us [64.275899] (kms_flip:2123) DEBUG: name = flip last_ts = 64.040131 last_received_ts = 64.039536 last_seq = 576 current_ts = 64.059036 current_received_ts = 64.058426 current_seq = 577 count = 109 seq_step = 1 [64.275949] (kms_flip:2123) DEBUG: Vblank took 36us [64.294836] (kms_flip:2123) DEBUG: name = flip last_ts = 64.059036 last_received_ts = 64.058426 last_seq = 577 current_ts = 64.077927 current_received_ts = 64.077286 current_seq = 578 count = 110 seq_step = 1 [64.294887] (kms_flip:2123) DEBUG: Vblank took 36us [64.313897] (kms_flip:2123) DEBUG: name = flip last_ts = 64.077927 last_received_ts = 64.077286 last_seq = 578 current_ts = 64.096802 current_received_ts = 64.096268 current_seq = 579 count = 111 seq_step = 1 [64.313948] (kms_flip:2123) DEBUG: Vblank took 35us [64.332881] (kms_flip:2123) DEBUG: name = flip last_ts = 64.096802 last_received_ts = 64.096268 last_seq = 579 current_ts = 64.115700 current_received_ts = 64.115166 current_seq = 580 count = 112 seq_step = 1 [64.332932] (kms_flip:2123) DEBUG: Vblank took 36us [64.351843] (kms_flip:2123) DEBUG: name = flip last_ts = 64.115700 last_received_ts = 64.115166 last_seq = 580 current_ts = 64.134598 current_received_ts = 64.134048 current_seq = 581 count = 113 seq_step = 1 [64.351893] (kms_flip:2123) DEBUG: Vblank took 35us [64.370740] (kms_flip:2123) DEBUG: name = flip last_ts = 64.134598 last_received_ts = 64.134048 last_seq = 581 current_ts = 64.153503 current_received_ts = 64.152878 current_seq = 582 count = 114 seq_step = 1 [64.370786] (kms_flip:2123) DEBUG: Vblank took 33us [64.389794] (kms_flip:2123) DEBUG: name = flip last_ts = 64.153503 last_received_ts = 64.152878 last_seq = 582 current_ts = 64.172401 current_received_ts = 64.171852 current_seq = 583 count = 115 seq_step = 1 [64.389847] (kms_flip:2123) DEBUG: Vblank took 37us [64.408726] (kms_flip:2123) DEBUG: name = flip last_ts = 64.172401 last_received_ts = 64.171852 last_seq = 583 current_ts = 64.191307 current_received_ts = 64.190712 current_seq = 584 count = 116 seq_step = 1 [64.408777] (kms_flip:2123) DEBUG: Vblank took 36us [64.427690] (kms_flip:2123) DEBUG: name = flip last_ts = 64.191307 last_received_ts = 64.190712 last_seq = 584 current_ts = 64.210205 current_received_ts = 64.209610 current_seq = 585 count = 117 seq_step = 1 [64.427738] (kms_flip:2123) DEBUG: Vblank took 35us [64.446633] (kms_flip:2123) DEBUG: name = flip last_ts = 64.210205 last_received_ts = 64.209610 last_seq = 585 current_ts = 64.229118 current_received_ts = 64.228485 current_seq = 586 count = 118 seq_step = 1 [64.446679] (kms_flip:2123) DEBUG: Vblank took 33us [64.465758] (kms_flip:2123) DEBUG: name = flip last_ts = 64.229118 last_received_ts = 64.228485 last_seq = 586 current_ts = 64.248016 current_received_ts = 64.247536 current_seq = 587 count = 119 seq_step = 1 [64.465805] (kms_flip:2123) DEBUG: Vblank took 34us [64.484707] (kms_flip:2123) DEBUG: name = flip last_ts = 64.248016 last_received_ts = 64.247536 last_seq = 587 current_ts = 64.266914 current_received_ts = 64.266418 current_seq = 588 count = 120 seq_step = 1 [64.484753] (kms_flip:2123) DEBUG: Vblank took 33us [64.503525] (kms_flip:2123) DEBUG: name = flip last_ts = 64.266914 last_received_ts = 64.266418 last_seq = 588 current_ts = 64.285812 current_received_ts = 64.285164 current_seq = 589 count = 121 seq_step = 1 [64.503583] (kms_flip:2123) DEBUG: Vblank took 42us [64.522529] (kms_flip:2123) DEBUG: name = flip last_ts = 64.285812 last_received_ts = 64.285164 last_seq = 589 current_ts = 64.304726 current_received_ts = 64.304108 current_seq = 590 count = 122 seq_step = 1 [64.522567] (kms_flip:2123) DEBUG: Vblank took 29us [64.541548] (kms_flip:2123) DEBUG: name = flip last_ts = 64.304726 last_received_ts = 64.304108 last_seq = 590 current_ts = 64.323624 current_received_ts = 64.323051 current_seq = 591 count = 123 seq_step = 1 [64.541603] (kms_flip:2123) DEBUG: Vblank took 42us [64.560485] (kms_flip:2123) DEBUG: name = flip last_ts = 64.323624 last_received_ts = 64.323051 last_seq = 591 current_ts = 64.342529 current_received_ts = 64.341919 current_seq = 592 count = 124 seq_step = 1 [64.560523] (kms_flip:2123) DEBUG: Vblank took 28us [64.579481] (kms_flip:2123) DEBUG: name = flip last_ts = 64.342529 last_received_ts = 64.341919 last_seq = 592 current_ts = 64.361443 current_received_ts = 64.360840 current_seq = 593 count = 125 seq_step = 1 [64.579528] (kms_flip:2123) DEBUG: Vblank took 32us [64.598529] (kms_flip:2123) DEBUG: name = flip last_ts = 64.361443 last_received_ts = 64.360840 last_seq = 593 current_ts = 64.380341 current_received_ts = 64.379814 current_seq = 594 count = 126 seq_step = 1 [64.598582] (kms_flip:2123) DEBUG: Vblank took 38us [64.617471] (kms_flip:2123) DEBUG: name = flip last_ts = 64.380341 last_received_ts = 64.379814 last_seq = 594 current_ts = 64.399246 current_received_ts = 64.398682 current_seq = 595 count = 127 seq_step = 1 [64.617520] (kms_flip:2123) DEBUG: Vblank took 34us [64.636387] (kms_flip:2123) DEBUG: name = flip last_ts = 64.399246 last_received_ts = 64.398682 last_seq = 595 current_ts = 64.418144 current_received_ts = 64.417534 current_seq = 596 count = 128 seq_step = 1 [64.636429] (kms_flip:2123) DEBUG: Vblank took 30us [64.655492] (kms_flip:2123) DEBUG: name = flip last_ts = 64.418144 last_received_ts = 64.417534 last_seq = 596 current_ts = 64.437050 current_received_ts = 64.436569 current_seq = 597 count = 129 seq_step = 1 [64.655539] (kms_flip:2123) DEBUG: Vblank took 34us [64.674374] (kms_flip:2123) DEBUG: name = flip last_ts = 64.437050 last_received_ts = 64.436569 last_seq = 597 current_ts = 64.455940 current_received_ts = 64.455376 current_seq = 598 count = 130 seq_step = 1 [64.674427] (kms_flip:2123) DEBUG: Vblank took 37us [64.693320] (kms_flip:2123) DEBUG: name = flip last_ts = 64.455940 last_received_ts = 64.455376 last_seq = 598 current_ts = 64.474854 current_received_ts = 64.474251 current_seq = 599 count = 131 seq_step = 1 [64.693370] (kms_flip:2123) DEBUG: Vblank took 36us [64.712362] (kms_flip:2123) DEBUG: name = flip last_ts = 64.474854 last_received_ts = 64.474251 last_seq = 599 current_ts = 64.493759 current_received_ts = 64.493225 current_seq = 600 count = 132 seq_step = 1 [64.712414] (kms_flip:2123) DEBUG: Vblank took 36us [64.731311] (kms_flip:2123) DEBUG: name = flip last_ts = 64.493759 last_received_ts = 64.493225 last_seq = 600 current_ts = 64.512665 current_received_ts = 64.512100 current_seq = 601 count = 133 seq_step = 1 [64.731362] (kms_flip:2123) DEBUG: Vblank took 36us [64.750216] (kms_flip:2123) DEBUG: name = flip last_ts = 64.512665 last_received_ts = 64.512100 last_seq = 601 current_ts = 64.531563 current_received_ts = 64.530937 current_seq = 602 count = 134 seq_step = 1 [64.750295] (kms_flip:2123) DEBUG: Vblank took 65us [64.769283] (kms_flip:2123) DEBUG: name = flip last_ts = 64.531563 last_received_ts = 64.530937 last_seq = 602 current_ts = 64.550468 current_received_ts = 64.549934 current_seq = 603 count = 135 seq_step = 1 [64.769362] (kms_flip:2123) DEBUG: Vblank took 64us [64.788195] (kms_flip:2123) DEBUG: name = flip last_ts = 64.550468 last_received_ts = 64.549934 last_seq = 603 current_ts = 64.569366 current_received_ts = 64.568771 current_seq = 604 count = 136 seq_step = 1 [64.788246] (kms_flip:2123) DEBUG: Vblank took 36us [64.807173] (kms_flip:2123) DEBUG: name = flip last_ts = 64.569366 last_received_ts = 64.568771 last_seq = 604 current_ts = 64.588272 current_received_ts = 64.587685 current_seq = 605 count = 137 seq_step = 1 [64.807224] (kms_flip:2123) DEBUG: Vblank took 36us [64.826129] (kms_flip:2123) DEBUG: name = flip last_ts = 64.588272 last_received_ts = 64.587685 last_seq = 605 current_ts = 64.607170 current_received_ts = 64.606567 current_seq = 606 count = 138 seq_step = 1 [64.826183] (kms_flip:2123) DEBUG: Vblank took 37us [64.845130] (kms_flip:2123) DEBUG: name = flip last_ts = 64.607170 last_received_ts = 64.606567 last_seq = 606 current_ts = 64.626083 current_received_ts = 64.625504 current_seq = 607 count = 139 seq_step = 1 [64.845211] (kms_flip:2123) DEBUG: Vblank took 38us [64.864127] (kms_flip:2123) DEBUG: name = flip last_ts = 64.626083 last_received_ts = 64.625504 last_seq = 607 current_ts = 64.644974 current_received_ts = 64.644424 current_seq = 608 count = 140 seq_step = 1 [64.864174] (kms_flip:2123) DEBUG: Vblank took 34us [64.883053] (kms_flip:2123) DEBUG: name = flip last_ts = 64.644974 last_received_ts = 64.644424 last_seq = 608 current_ts = 64.663879 current_received_ts = 64.663277 current_seq = 609 count = 141 seq_step = 1 [64.883106] (kms_flip:2123) DEBUG: Vblank took 36us [64.902029] (kms_flip:2123) DEBUG: name = flip last_ts = 64.663879 last_received_ts = 64.663277 last_seq = 609 current_ts = 64.682785 current_received_ts = 64.682182 current_seq = 610 count = 142 seq_step = 1 [64.902081] (kms_flip:2123) DEBUG: Vblank took 36us [64.920978] (kms_flip:2123) DEBUG: name = flip last_ts = 64.682785 last_received_ts = 64.682182 last_seq = 610 current_ts = 64.701683 current_received_ts = 64.701073 current_seq = 611 count = 143 seq_step = 1 [64.921020] (kms_flip:2123) DEBUG: Vblank took 31us [64.940025] (kms_flip:2123) DEBUG: name = flip last_ts = 64.701683 last_received_ts = 64.701073 last_seq = 611 current_ts = 64.720596 current_received_ts = 64.720032 current_seq = 612 count = 144 seq_step = 1 [64.940078] (kms_flip:2123) DEBUG: Vblank took 37us [64.958968] (kms_flip:2123) DEBUG: name = flip last_ts = 64.720596 last_received_ts = 64.720032 last_seq = 612 current_ts = 64.739502 current_received_ts = 64.738907 current_seq = 613 count = 145 seq_step = 1 [64.959019] (kms_flip:2123) DEBUG: Vblank took 36us [64.977939] (kms_flip:2123) DEBUG: name = flip last_ts = 64.739502 last_received_ts = 64.738907 last_seq = 613 current_ts = 64.758408 current_received_ts = 64.757812 current_seq = 614 count = 146 seq_step = 1 [64.977992] (kms_flip:2123) DEBUG: Vblank took 36us [64.996887] (kms_flip:2123) DEBUG: name = flip last_ts = 64.758408 last_received_ts = 64.757812 last_seq = 614 current_ts = 64.777298 current_received_ts = 64.776688 current_seq = 615 count = 147 seq_step = 1 [64.996939] (kms_flip:2123) DEBUG: Vblank took 36us [65.015933] (kms_flip:2123) DEBUG: name = flip last_ts = 64.777298 last_received_ts = 64.776688 last_seq = 615 current_ts = 64.796211 current_received_ts = 64.795662 current_seq = 616 count = 148 seq_step = 1 [65.015985] (kms_flip:2123) DEBUG: Vblank took 35us [65.034848] (kms_flip:2123) DEBUG: name = flip last_ts = 64.796211 last_received_ts = 64.795662 last_seq = 616 current_ts = 64.815102 current_received_ts = 64.814514 current_seq = 617 count = 149 seq_step = 1 [65.034893] (kms_flip:2123) DEBUG: Vblank took 32us [65.053833] (kms_flip:2123) DEBUG: name = flip last_ts = 64.815102 last_received_ts = 64.814514 last_seq = 617 current_ts = 64.834007 current_received_ts = 64.833420 current_seq = 618 count = 150 seq_step = 1 [65.053885] (kms_flip:2123) DEBUG: Vblank took 36us [65.072851] (kms_flip:2123) DEBUG: name = flip last_ts = 64.834007 last_received_ts = 64.833420 last_seq = 618 current_ts = 64.852921 current_received_ts = 64.852371 current_seq = 619 count = 151 seq_step = 1 [65.072903] (kms_flip:2123) DEBUG: Vblank took 36us [65.091809] (kms_flip:2123) DEBUG: name = flip last_ts = 64.852921 last_received_ts = 64.852371 last_seq = 619 current_ts = 64.871819 current_received_ts = 64.871254 current_seq = 620 count = 152 seq_step = 1 [65.091864] (kms_flip:2123) DEBUG: Vblank took 37us [65.110746] (kms_flip:2123) DEBUG: name = flip last_ts = 64.871819 last_received_ts = 64.871254 last_seq = 620 current_ts = 64.890724 current_received_ts = 64.890121 current_seq = 621 count = 153 seq_step = 1 [65.110798] (kms_flip:2123) DEBUG: Vblank took 36us [65.129752] (kms_flip:2123) DEBUG: name = flip last_ts = 64.890724 last_received_ts = 64.890121 last_seq = 621 current_ts = 64.909622 current_received_ts = 64.909058 current_seq = 622 count = 154 seq_step = 1 [65.129803] (kms_flip:2123) DEBUG: Vblank took 36us [65.148774] (kms_flip:2123) DEBUG: name = flip last_ts = 64.909622 last_received_ts = 64.909058 last_seq = 622 current_ts = 64.928528 current_received_ts = 64.927979 current_seq = 623 count = 155 seq_step = 1 [65.148830] (kms_flip:2123) DEBUG: Vblank took 38us [65.167679] (kms_flip:2123) DEBUG: name = flip last_ts = 64.928528 last_received_ts = 64.927979 last_seq = 623 current_ts = 64.947433 current_received_ts = 64.946846 current_seq = 624 count = 156 seq_step = 1 [65.167730] (kms_flip:2123) DEBUG: Vblank took 36us [65.186832] (kms_flip:2123) DEBUG: name = flip last_ts = 64.947433 last_received_ts = 64.946846 last_seq = 624 current_ts = 64.966331 current_received_ts = 64.965927 current_seq = 625 count = 157 seq_step = 1 [65.186914] (kms_flip:2123) DEBUG: Vblank took 65us [65.205597] (kms_flip:2123) DEBUG: name = flip last_ts = 64.966331 last_received_ts = 64.965927 last_seq = 625 current_ts = 64.985229 current_received_ts = 64.984627 current_seq = 626 count = 158 seq_step = 1 [65.205648] (kms_flip:2123) DEBUG: Vblank took 35us [65.224648] (kms_flip:2123) DEBUG: name = flip last_ts = 64.985229 last_received_ts = 64.984627 last_seq = 626 current_ts = 65.004150 current_received_ts = 65.003601 current_seq = 627 count = 159 seq_step = 1 [65.224728] (kms_flip:2123) DEBUG: Vblank took 64us [65.243589] (kms_flip:2123) DEBUG: name = flip last_ts = 65.004150 last_received_ts = 65.003601 last_seq = 627 current_ts = 65.023041 current_received_ts = 65.022469 current_seq = 628 count = 160 seq_step = 1 [65.243641] (kms_flip:2123) DEBUG: Vblank took 36us [65.262500] (kms_flip:2123) DEBUG: name = flip last_ts = 65.023041 last_received_ts = 65.022469 last_seq = 628 current_ts = 65.041946 current_received_ts = 65.041321 current_seq = 629 count = 161 seq_step = 1 [65.262543] (kms_flip:2123) DEBUG: Vblank took 32us [65.281500] (kms_flip:2123) DEBUG: name = flip last_ts = 65.041946 last_received_ts = 65.041321 last_seq = 629 current_ts = 65.060844 current_received_ts = 65.060242 current_seq = 630 count = 162 seq_step = 1 [65.281552] (kms_flip:2123) DEBUG: Vblank took 36us [65.300567] (kms_flip:2123) DEBUG: name = flip last_ts = 65.060844 last_received_ts = 65.060242 last_seq = 630 current_ts = 65.079750 current_received_ts = 65.079239 current_seq = 631 count = 163 seq_step = 1 [65.300620] (kms_flip:2123) DEBUG: Vblank took 36us [65.319465] (kms_flip:2123) DEBUG: name = flip last_ts = 65.079750 last_received_ts = 65.079239 last_seq = 631 current_ts = 65.098656 current_received_ts = 65.098068 current_seq = 632 count = 164 seq_step = 1 [65.319518] (kms_flip:2123) DEBUG: Vblank took 37us [65.338519] (kms_flip:2123) DEBUG: name = flip last_ts = 65.098656 last_received_ts = 65.098068 last_seq = 632 current_ts = 65.117561 current_received_ts = 65.117050 current_seq = 633 count = 165 seq_step = 1 [65.338571] (kms_flip:2123) DEBUG: Vblank took 36us [65.357422] (kms_flip:2123) DEBUG: name = flip last_ts = 65.117561 last_received_ts = 65.117050 last_seq = 633 current_ts = 65.136452 current_received_ts = 65.135880 current_seq = 634 count = 166 seq_step = 1 [65.357476] (kms_flip:2123) DEBUG: Vblank took 37us [65.376434] (kms_flip:2123) DEBUG: name = flip last_ts = 65.136452 last_received_ts = 65.135880 last_seq = 634 current_ts = 65.155373 current_received_ts = 65.154831 current_seq = 635 count = 167 seq_step = 1 [65.376486] (kms_flip:2123) DEBUG: Vblank took 36us [65.395462] (kms_flip:2123) DEBUG: name = flip last_ts = 65.155373 last_received_ts = 65.154831 last_seq = 635 current_ts = 65.174278 current_received_ts = 65.173798 current_seq = 636 count = 168 seq_step = 1 [65.395510] (kms_flip:2123) DEBUG: Vblank took 34us [65.414342] (kms_flip:2123) DEBUG: name = flip last_ts = 65.174278 last_received_ts = 65.173798 last_seq = 636 current_ts = 65.193192 current_received_ts = 65.192612 current_seq = 637 count = 169 seq_step = 1 [65.414394] (kms_flip:2123) DEBUG: Vblank took 36us [65.433402] (kms_flip:2123) DEBUG: name = flip last_ts = 65.193192 last_received_ts = 65.192612 last_seq = 637 current_ts = 65.212120 current_received_ts = 65.211617 current_seq = 638 count = 170 seq_step = 1 [65.433453] (kms_flip:2123) DEBUG: Vblank took 36us [65.452264] (kms_flip:2123) DEBUG: name = flip last_ts = 65.212120 last_received_ts = 65.211617 last_seq = 638 current_ts = 65.231018 current_received_ts = 65.230415 current_seq = 639 count = 171 seq_step = 1 [65.452317] (kms_flip:2123) DEBUG: Vblank took 37us [65.471300] (kms_flip:2123) DEBUG: name = flip last_ts = 65.231018 last_received_ts = 65.230415 last_seq = 639 current_ts = 65.249931 current_received_ts = 65.249390 current_seq = 640 count = 172 seq_step = 1 [65.471352] (kms_flip:2123) DEBUG: Vblank took 37us [65.490263] (kms_flip:2123) DEBUG: name = flip last_ts = 65.249931 last_received_ts = 65.249390 last_seq = 640 current_ts = 65.268845 current_received_ts = 65.268288 current_seq = 641 count = 173 seq_step = 1 [65.490317] (kms_flip:2123) DEBUG: Vblank took 37us [65.509165] (kms_flip:2123) DEBUG: name = flip last_ts = 65.268845 last_received_ts = 65.268288 last_seq = 641 current_ts = 65.287758 current_received_ts = 65.287140 current_seq = 642 count = 174 seq_step = 1 [65.509206] (kms_flip:2123) DEBUG: Vblank took 30us [65.528163] (kms_flip:2123) DEBUG: name = flip last_ts = 65.287758 last_received_ts = 65.287140 last_seq = 642 current_ts = 65.306664 current_received_ts = 65.306068 current_seq = 643 count = 175 seq_step = 1 [65.528211] (kms_flip:2123) DEBUG: Vblank took 33us [65.547231] (kms_flip:2123) DEBUG: name = flip last_ts = 65.306664 last_received_ts = 65.306068 last_seq = 643 current_ts = 65.325584 current_received_ts = 65.325081 current_seq = 644 count = 176 seq_step = 1 [65.547271] (kms_flip:2123) DEBUG: Vblank took 29us [65.566126] (kms_flip:2123) DEBUG: name = flip last_ts = 65.325584 last_received_ts = 65.325081 last_seq = 644 current_ts = 65.344498 current_received_ts = 65.343903 current_seq = 645 count = 177 seq_step = 1 [65.566177] (kms_flip:2123) DEBUG: Vblank took 36us [65.585190] (kms_flip:2123) DEBUG: name = flip last_ts = 65.344498 last_received_ts = 65.343903 last_seq = 645 current_ts = 65.363411 current_received_ts = 65.362907 current_seq = 646 count = 178 seq_step = 1 [65.585243] (kms_flip:2123) DEBUG: Vblank took 36us [65.604038] (kms_flip:2123) DEBUG: name = flip last_ts = 65.363411 last_received_ts = 65.362907 last_seq = 646 current_ts = 65.382317 current_received_ts = 65.381706 current_seq = 647 count = 179 seq_step = 1 [65.604077] (kms_flip:2123) DEBUG: Vblank took 29us [65.622994] (kms_flip:2123) DEBUG: name = flip last_ts = 65.382317 last_received_ts = 65.381706 last_seq = 647 current_ts = 65.401222 current_received_ts = 65.400597 current_seq = 648 count = 180 seq_step = 1 [65.623033] (kms_flip:2123) DEBUG: Vblank took 29us [65.642049] (kms_flip:2123) DEBUG: name = flip last_ts = 65.401222 last_received_ts = 65.400597 last_seq = 648 current_ts = 65.420151 current_received_ts = 65.419586 current_seq = 649 count = 181 seq_step = 1 [65.642092] (kms_flip:2123) DEBUG: Vblank took 31us [65.661091] (kms_flip:2123) DEBUG: name = flip last_ts = 65.420151 last_received_ts = 65.419586 last_seq = 649 current_ts = 65.439056 current_received_ts = 65.438560 current_seq = 650 count = 182 seq_step = 1 [65.661144] (kms_flip:2123) DEBUG: Vblank took 37us [65.679926] (kms_flip:2123) DEBUG: name = flip last_ts = 65.439056 last_received_ts = 65.438560 last_seq = 650 current_ts = 65.457962 current_received_ts = 65.457344 current_seq = 651 count = 183 seq_step = 1 [65.679969] (kms_flip:2123) DEBUG: Vblank took 32us [65.698925] (kms_flip:2123) DEBUG: name = flip last_ts = 65.457962 last_received_ts = 65.457344 last_seq = 651 current_ts = 65.476875 current_received_ts = 65.476273 current_seq = 652 count = 184 seq_step = 1 [65.698977] (kms_flip:2123) DEBUG: Vblank took 36us [65.717979] (kms_flip:2123) DEBUG: name = flip last_ts = 65.476875 last_received_ts = 65.476273 last_seq = 652 current_ts = 65.495796 current_received_ts = 65.495262 current_seq = 653 count = 185 seq_step = 1 [65.718063] (kms_flip:2123) DEBUG: Vblank took 68us [65.736959] (kms_flip:2123) DEBUG: name = flip last_ts = 65.495796 last_received_ts = 65.495262 last_seq = 653 current_ts = 65.514702 current_received_ts = 65.514183 current_seq = 654 count = 186 seq_step = 1 [65.737006] (kms_flip:2123) DEBUG: Vblank took 35us [65.755879] (kms_flip:2123) DEBUG: name = flip last_ts = 65.514702 last_received_ts = 65.514183 last_seq = 654 current_ts = 65.533615 current_received_ts = 65.533043 current_seq = 655 count = 187 seq_step = 1 [65.755930] (kms_flip:2123) DEBUG: Vblank took 36us [65.774807] (kms_flip:2123) DEBUG: name = flip last_ts = 65.533615 last_received_ts = 65.533043 last_seq = 655 current_ts = 65.552521 current_received_ts = 65.551918 current_seq = 656 count = 188 seq_step = 1 [65.774850] (kms_flip:2123) DEBUG: Vblank took 31us [65.793791] (kms_flip:2123) DEBUG: name = flip last_ts = 65.552521 last_received_ts = 65.551918 last_seq = 656 current_ts = 65.571426 current_received_ts = 65.570831 current_seq = 657 count = 189 seq_step = 1 [65.793844] (kms_flip:2123) DEBUG: Vblank took 36us [65.812840] (kms_flip:2123) DEBUG: name = flip last_ts = 65.571426 last_received_ts = 65.570831 last_seq = 657 current_ts = 65.590347 current_received_ts = 65.589821 current_seq = 658 count = 190 seq_step = 1 [65.812889] (kms_flip:2123) DEBUG: Vblank took 34us [65.831726] (kms_flip:2123) DEBUG: name = flip last_ts = 65.590347 last_received_ts = 65.589821 last_seq = 658 current_ts = 65.609261 current_received_ts = 65.608650 current_seq = 659 count = 191 seq_step = 1 [65.831765] (kms_flip:2123) DEBUG: Vblank took 29us [65.850737] (kms_flip:2123) DEBUG: name = flip last_ts = 65.609261 last_received_ts = 65.608650 last_seq = 659 current_ts = 65.628181 current_received_ts = 65.627586 current_seq = 660 count = 192 seq_step = 1 [65.850789] (kms_flip:2123) DEBUG: Vblank took 36us [65.869668] (kms_flip:2123) DEBUG: name = flip last_ts = 65.628181 last_received_ts = 65.627586 last_seq = 660 current_ts = 65.647087 current_received_ts = 65.646461 current_seq = 661 count = 193 seq_step = 1 [65.869716] (kms_flip:2123) DEBUG: Vblank took 35us [65.888700] (kms_flip:2123) DEBUG: name = flip last_ts = 65.647087 last_received_ts = 65.646461 last_seq = 661 current_ts = 65.666000 current_received_ts = 65.665436 current_seq = 662 count = 194 seq_step = 1 [65.888744] (kms_flip:2123) DEBUG: Vblank took 32us [65.907668] (kms_flip:2123) DEBUG: name = flip last_ts = 65.666000 last_received_ts = 65.665436 last_seq = 662 current_ts = 65.684914 current_received_ts = 65.684341 current_seq = 663 count = 195 seq_step = 1 [65.907712] (kms_flip:2123) DEBUG: Vblank took 32us [65.926612] (kms_flip:2123) DEBUG: name = flip last_ts = 65.684914 last_received_ts = 65.684341 last_seq = 663 current_ts = 65.703819 current_received_ts = 65.703217 current_seq = 664 count = 196 seq_step = 1 [65.926663] (kms_flip:2123) DEBUG: Vblank took 36us [65.945588] (kms_flip:2123) DEBUG: name = flip last_ts = 65.703819 last_received_ts = 65.703217 last_seq = 664 current_ts = 65.722740 current_received_ts = 65.722130 current_seq = 665 count = 197 seq_step = 1 [65.945641] (kms_flip:2123) DEBUG: Vblank took 36us [65.965318] (kms_flip:2123) DEBUG: name = flip last_ts = 65.722740 last_received_ts = 65.722130 last_seq = 665 current_ts = 65.741653 current_received_ts = 65.741806 current_seq = 666 count = 198 seq_step = 1 [65.965360] (kms_flip:2123) DEBUG: Vblank took 31us [65.983536] (kms_flip:2123) DEBUG: name = flip last_ts = 65.741653 last_received_ts = 65.741806 last_seq = 666 current_ts = 65.760559 current_received_ts = 65.759956 current_seq = 667 count = 199 seq_step = 1 [65.984381] (kms_flip:2123) DEBUG: Vblank took 827us [65.984389] (kms_flip:2123) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931: [65.984450] (kms_flip:2123) CRITICAL: Failed assertion: end - start < 500 [65.984493] (kms_flip:2123) CRITICAL: Last errno: 4, Interrupted system call [66.023288] (kms_flip:2123) igt_core-INFO: Stack trace: [66.040315] (kms_flip:2123) igt_core-INFO: #0 ../lib/igt_core.c:2074 __igt_fail_assert() [66.041516] (kms_flip:2123) igt_core-INFO: #1 ../tests/kms_flip.c:926 run_test_on_crtc_set() [66.041586] (kms_flip:2123) igt_core-INFO: #2 ../tests/kms_flip.c:1920 run_test() [66.041636] (kms_flip:2123) igt_core-INFO: #3 ../tests/kms_flip.c:2220 __igt_unique____real_main2105() [66.041667] (kms_flip:2123) igt_core-INFO: #4 ../tests/kms_flip.c:2105 main() [66.048324] (kms_flip:2123) igt_core-INFO: #5 [__libc_init_first+0x8a] [66.049464] (kms_flip:2123) igt_core-INFO: #6 [__libc_start_main+0x8b] [66.049686] (kms_flip:2123) igt_core-INFO: #7 [_start+0x25] **** END **** Dynamic subtest A-HDMI-A2: FAIL (4.073s) Starting dynamic subtest: B-HDMI-A2 Dynamic subtest B-HDMI-A2: SUCCESS (10.171s) Starting dynamic subtest: C-HDMI-A2 [77.772600] (kms_flip:2123) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931: [77.772715] (kms_flip:2123) CRITICAL: Failed assertion: end - start < 500 [77.772765] (kms_flip:2123) CRITICAL: Last errno: 4, Interrupted system call Dynamic subtest C-HDMI-A2 failed. **** DEBUG **** [76.255059] (kms_flip:2123) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x100000000000001, size=0) [76.255076] (kms_flip:2123) drmtest-DEBUG: Test requirement passed: is_intel_device(fd) [76.255093] (kms_flip:2123) drmtest-DEBUG: Test requirement passed: is_intel_device(fd) [76.255203] (kms_flip:2123) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=3, pitch=7680) [76.255207] (kms_flip:2123) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd) [76.255268] (kms_flip:2123) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x100000000000001, size=0) [76.255281] (kms_flip:2123) drmtest-DEBUG: Test requirement passed: is_intel_device(fd) [76.255298] (kms_flip:2123) drmtest-DEBUG: Test requirement passed: is_intel_device(fd) [76.255389] (kms_flip:2123) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=4, pitch=7680) [76.255391] (kms_flip:2123) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd) [76.255448] (kms_flip:2123) intel_blt-DEBUG: Test requirement passed: cmds_info [76.255474] (kms_flip:2123) intel_blt-DEBUG: Test requirement passed: cmds_info [76.257125] (kms_flip:2123) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS [76.264611] (kms_flip:2123) intel_blt-DEBUG: Test requirement passed: cmds_info [76.264637] (kms_flip:2123) intel_blt-DEBUG: Test requirement passed: cmds_info [76.265518] (kms_flip:2123) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS [76.272574] (kms_flip:2123) igt_kms-INFO: 1920x1080: 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5 [76.272619] (kms_flip:2123) DEBUG: No stale events found [76.351934] (kms_flip:2123) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0 [76.352202] (kms_flip:2123) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0 [76.386510] (kms_flip:2123) DEBUG: name = flip last_ts = 0.000000 last_received_ts = 0.000000 last_seq = 0 current_ts = 76.145508 current_received_ts = 76.144859 current_seq = 5 count = 0 seq_step = 1 [76.386563] (kms_flip:2123) DEBUG: Vblank took 36us [76.405507] (kms_flip:2123) DEBUG: name = flip last_ts = 76.145508 last_received_ts = 76.144859 last_seq = 5 current_ts = 76.164467 current_received_ts = 76.163841 current_seq = 6 count = 1 seq_step = 1 [76.405554] (kms_flip:2123) DEBUG: Vblank took 33us [76.424475] (kms_flip:2123) DEBUG: name = flip last_ts = 76.164467 last_received_ts = 76.163841 last_seq = 6 current_ts = 76.183426 current_received_ts = 76.182793 current_seq = 7 count = 2 seq_step = 1 [76.424524] (kms_flip:2123) DEBUG: Vblank took 34us [76.443458] (kms_flip:2123) DEBUG: name = flip last_ts = 76.183426 last_received_ts = 76.182793 last_seq = 7 current_ts = 76.202385 current_received_ts = 76.201767 current_seq = 8 count = 3 seq_step = 1 [76.443506] (kms_flip:2123) DEBUG: Vblank took 34us [76.462481] (kms_flip:2123) DEBUG: name = flip last_ts = 76.202385 last_received_ts = 76.201767 last_seq = 8 current_ts = 76.221344 current_received_ts = 76.220772 current_seq = 9 count = 4 seq_step = 1 [76.462529] (kms_flip:2123) DEBUG: Vblank took 34us [76.481394] (kms_flip:2123) DEBUG: name = flip last_ts = 76.221344 last_received_ts = 76.220772 last_seq = 9 current_ts = 76.240303 current_received_ts = 76.239670 current_seq = 10 count = 5 seq_step = 1 [76.481443] (kms_flip:2123) DEBUG: Vblank took 34us [76.500350] (kms_flip:2123) DEBUG: name = flip last_ts = 76.240303 last_received_ts = 76.239670 last_seq = 10 current_ts = 76.259262 current_received_ts = 76.258621 current_seq = 11 count = 6 seq_step = 1 [76.500391] (kms_flip:2123) DEBUG: Vblank took 30us [76.519346] (kms_flip:2123) DEBUG: name = flip last_ts = 76.259262 last_received_ts = 76.258621 last_seq = 11 current_ts = 76.278221 current_received_ts = 76.277596 current_seq = 12 count = 7 seq_step = 1 [76.519396] (kms_flip:2123) DEBUG: Vblank took 35us [76.538334] (kms_flip:2123) DEBUG: name = flip last_ts = 76.278221 last_received_ts = 76.277596 last_seq = 12 current_ts = 76.297180 current_received_ts = 76.296570 current_seq = 13 count = 8 seq_step = 1 [76.538385] (kms_flip:2123) DEBUG: Vblank took 34us [76.557355] (kms_flip:2123) DEBUG: name = flip last_ts = 76.297180 last_received_ts = 76.296570 last_seq = 13 current_ts = 76.316147 current_received_ts = 76.315575 current_seq = 14 count = 9 seq_step = 1 [76.557403] (kms_flip:2123) DEBUG: Vblank took 34us [76.576279] (kms_flip:2123) DEBUG: name = flip last_ts = 76.316147 last_received_ts = 76.315575 last_seq = 14 current_ts = 76.335091 current_received_ts = 76.334488 current_seq = 15 count = 10 seq_step = 1 [76.576327] (kms_flip:2123) DEBUG: Vblank took 34us [76.595204] (kms_flip:2123) DEBUG: name = flip last_ts = 76.335091 last_received_ts = 76.334488 last_seq = 15 current_ts = 76.354057 current_received_ts = 76.353401 current_seq = 16 count = 11 seq_step = 1 [76.595245] (kms_flip:2123) DEBUG: Vblank took 30us [76.614303] (kms_flip:2123) DEBUG: name = flip last_ts = 76.354057 last_received_ts = 76.353401 last_seq = 16 current_ts = 76.373024 current_received_ts = 76.372490 current_seq = 17 count = 12 seq_step = 1 [76.614344] (kms_flip:2123) DEBUG: Vblank took 31us [76.633217] (kms_flip:2123) DEBUG: name = flip last_ts = 76.373024 last_received_ts = 76.372490 last_seq = 17 current_ts = 76.391983 current_received_ts = 76.391388 current_seq = 18 count = 13 seq_step = 1 [76.633255] (kms_flip:2123) DEBUG: Vblank took 29us [76.652198] (kms_flip:2123) DEBUG: name = flip last_ts = 76.391983 last_received_ts = 76.391388 last_seq = 18 current_ts = 76.410927 current_received_ts = 76.410347 current_seq = 19 count = 14 seq_step = 1 [76.652243] (kms_flip:2123) DEBUG: Vblank took 31us [76.671124] (kms_flip:2123) DEBUG: name = flip last_ts = 76.410927 last_received_ts = 76.410347 last_seq = 19 current_ts = 76.429886 current_received_ts = 76.429260 current_seq = 20 count = 15 seq_step = 1 [76.671172] (kms_flip:2123) DEBUG: Vblank took 34us [76.690102] (kms_flip:2123) DEBUG: name = flip last_ts = 76.429886 last_received_ts = 76.429260 last_seq = 20 current_ts = 76.448853 current_received_ts = 76.448219 current_seq = 21 count = 16 seq_step = 1 [76.690153] (kms_flip:2123) DEBUG: Vblank took 34us [76.709078] (kms_flip:2123) DEBUG: name = flip last_ts = 76.448853 last_received_ts = 76.448219 last_seq = 21 current_ts = 76.467812 current_received_ts = 76.467186 current_seq = 22 count = 17 seq_step = 1 [76.709125] (kms_flip:2123) DEBUG: Vblank took 33us [76.728070] (kms_flip:2123) DEBUG: name = flip last_ts = 76.467812 last_received_ts = 76.467186 last_seq = 22 current_ts = 76.486771 current_received_ts = 76.486160 current_seq = 23 count = 18 seq_step = 1 [76.728118] (kms_flip:2123) DEBUG: Vblank took 34us [76.747044] (kms_flip:2123) DEBUG: name = flip last_ts = 76.486771 last_received_ts = 76.486160 last_seq = 23 current_ts = 76.505730 current_received_ts = 76.505119 current_seq = 24 count = 19 seq_step = 1 [76.747093] (kms_flip:2123) DEBUG: Vblank took 34us [76.766131] (kms_flip:2123) DEBUG: name = flip last_ts = 76.505730 last_received_ts = 76.505119 last_seq = 24 current_ts = 76.524689 current_received_ts = 76.524193 current_seq = 25 count = 20 seq_step = 1 [76.766181] (kms_flip:2123) DEBUG: Vblank took 35us [76.785005] (kms_flip:2123) DEBUG: name = flip last_ts = 76.524689 last_received_ts = 76.524193 last_seq = 25 current_ts = 76.543655 current_received_ts = 76.543053 current_seq = 26 count = 21 seq_step = 1 [76.785055] (kms_flip:2123) DEBUG: Vblank took 34us [76.803977] (kms_flip:2123) DEBUG: name = flip last_ts = 76.543655 last_received_ts = 76.543053 last_seq = 26 current_ts = 76.562607 current_received_ts = 76.561981 current_seq = 27 count = 22 seq_step = 1 [76.804042] (kms_flip:2123) DEBUG: Vblank took 47us [76.823023] (kms_flip:2123) DEBUG: name = flip last_ts = 76.562607 last_received_ts = 76.561981 last_seq = 27 current_ts = 76.581566 current_received_ts = 76.581039 current_seq = 28 count = 23 seq_step = 1 [76.823110] (kms_flip:2123) DEBUG: Vblank took 71us [76.841906] (kms_flip:2123) DEBUG: name = flip last_ts = 76.581566 last_received_ts = 76.581039 last_seq = 28 current_ts = 76.600540 current_received_ts = 76.599915 current_seq = 29 count = 24 seq_step = 1 [76.841956] (kms_flip:2123) DEBUG: Vblank took 33us [76.860879] (kms_flip:2123) DEBUG: name = flip last_ts = 76.600540 last_received_ts = 76.599915 last_seq = 29 current_ts = 76.619499 current_received_ts = 76.618874 current_seq = 30 count = 25 seq_step = 1 [76.860927] (kms_flip:2123) DEBUG: Vblank took 35us [76.879821] (kms_flip:2123) DEBUG: name = flip last_ts = 76.619499 last_received_ts = 76.618874 last_seq = 30 current_ts = 76.638451 current_received_ts = 76.637810 current_seq = 31 count = 26 seq_step = 1 [76.879862] (kms_flip:2123) DEBUG: Vblank took 30us [76.898790] (kms_flip:2123) DEBUG: name = flip last_ts = 76.638451 last_received_ts = 76.637810 last_seq = 31 current_ts = 76.657417 current_received_ts = 76.656761 current_seq = 32 count = 27 seq_step = 1 [76.898831] (kms_flip:2123) DEBUG: Vblank took 30us [76.917853] (kms_flip:2123) DEBUG: name = flip last_ts = 76.657417 last_received_ts = 76.656761 last_seq = 32 current_ts = 76.676376 current_received_ts = 76.675804 current_seq = 33 count = 28 seq_step = 1 [76.917902] (kms_flip:2123) DEBUG: Vblank took 34us [76.936772] (kms_flip:2123) DEBUG: name = flip last_ts = 76.676376 last_received_ts = 76.675804 last_seq = 33 current_ts = 76.695335 current_received_ts = 76.694710 current_seq = 34 count = 29 seq_step = 1 [76.936820] (kms_flip:2123) DEBUG: Vblank took 34us [76.955801] (kms_flip:2123) DEBUG: name = flip last_ts = 76.695335 last_received_ts = 76.694710 last_seq = 34 current_ts = 76.714294 current_received_ts = 76.713722 current_seq = 35 count = 30 seq_step = 1 [76.955850] (kms_flip:2123) DEBUG: Vblank took 36us [76.974704] (kms_flip:2123) DEBUG: name = flip last_ts = 76.714294 last_received_ts = 76.713722 last_seq = 35 current_ts = 76.733238 current_received_ts = 76.732613 current_seq = 36 count = 31 seq_step = 1 [76.974754] (kms_flip:2123) DEBUG: Vblank took 34us [76.993778] (kms_flip:2123) DEBUG: name = flip last_ts = 76.733238 last_received_ts = 76.732613 last_seq = 36 current_ts = 76.752213 current_received_ts = 76.751671 current_seq = 37 count = 32 seq_step = 1 [76.993829] (kms_flip:2123) DEBUG: Vblank took 34us [77.012720] (kms_flip:2123) DEBUG: name = flip last_ts = 76.752213 last_received_ts = 76.751671 last_seq = 37 current_ts = 76.771172 current_received_ts = 76.770599 current_seq = 38 count = 33 seq_step = 1 [77.012768] (kms_flip:2123) DEBUG: Vblank took 34us [77.031642] (kms_flip:2123) DEBUG: name = flip last_ts = 76.771172 last_received_ts = 76.770599 last_seq = 38 current_ts = 76.790131 current_received_ts = 76.789505 current_seq = 39 count = 34 seq_step = 1 [77.031691] (kms_flip:2123) DEBUG: Vblank took 34us [77.050613] (kms_flip:2123) DEBUG: name = flip last_ts = 76.790131 last_received_ts = 76.789505 last_seq = 39 current_ts = 76.809090 current_received_ts = 76.808464 current_seq = 40 count = 35 seq_step = 1 [77.050662] (kms_flip:2123) DEBUG: Vblank took 33us [77.069591] (kms_flip:2123) DEBUG: name = flip last_ts = 76.809090 last_received_ts = 76.808464 last_seq = 40 current_ts = 76.828049 current_received_ts = 76.827423 current_seq = 41 count = 36 seq_step = 1 [77.069641] (kms_flip:2123) DEBUG: Vblank took 34us [77.088609] (kms_flip:2123) DEBUG: name = flip last_ts = 76.828049 last_received_ts = 76.827423 last_seq = 41 current_ts = 76.847000 current_received_ts = 76.846428 current_seq = 42 count = 37 seq_step = 1 [77.088659] (kms_flip:2123) DEBUG: Vblank took 35us [77.107541] (kms_flip:2123) DEBUG: name = flip last_ts = 76.847000 last_received_ts = 76.846428 last_seq = 42 current_ts = 76.865959 current_received_ts = 76.865349 current_seq = 43 count = 38 seq_step = 1 [77.107589] (kms_flip:2123) DEBUG: Vblank took 34us [77.126468] (kms_flip:2123) DEBUG: name = flip last_ts = 76.865959 last_received_ts = 76.865349 last_seq = 43 current_ts = 76.884926 current_received_ts = 76.884270 current_seq = 44 count = 39 seq_step = 1 [77.126509] (kms_flip:2123) DEBUG: Vblank took 31us [77.145471] (kms_flip:2123) DEBUG: name = flip last_ts = 76.884926 last_received_ts = 76.884270 last_seq = 44 current_ts = 76.903877 current_received_ts = 76.903252 current_seq = 45 count = 40 seq_step = 1 [77.145522] (kms_flip:2123) DEBUG: Vblank took 34us [77.164492] (kms_flip:2123) DEBUG: name = flip last_ts = 76.903877 last_received_ts = 76.903252 last_seq = 45 current_ts = 76.922852 current_received_ts = 76.922256 current_seq = 46 count = 41 seq_step = 1 [77.164541] (kms_flip:2123) DEBUG: Vblank took 34us [77.183459] (kms_flip:2123) DEBUG: name = flip last_ts = 76.922852 last_received_ts = 76.922256 last_seq = 46 current_ts = 76.941803 current_received_ts = 76.941216 current_seq = 47 count = 42 seq_step = 1 [77.183500] (kms_flip:2123) DEBUG: Vblank took 30us [77.202517] (kms_flip:2123) DEBUG: name = flip last_ts = 76.941803 last_received_ts = 76.941216 last_seq = 47 current_ts = 76.960770 current_received_ts = 76.960251 current_seq = 48 count = 43 seq_step = 1 [77.202569] (kms_flip:2123) DEBUG: Vblank took 36us [77.221392] (kms_flip:2123) DEBUG: name = flip last_ts = 76.960770 last_received_ts = 76.960251 last_seq = 48 current_ts = 76.979729 current_received_ts = 76.979111 current_seq = 49 count = 44 seq_step = 1 [77.221441] (kms_flip:2123) DEBUG: Vblank took 34us [77.240383] (kms_flip:2123) DEBUG: name = flip last_ts = 76.979729 last_received_ts = 76.979111 last_seq = 49 current_ts = 76.998680 current_received_ts = 76.998093 current_seq = 50 count = 45 seq_step = 1 [77.240433] (kms_flip:2123) DEBUG: Vblank took 34us [77.259509] (kms_flip:2123) DEBUG: name = flip last_ts = 76.998680 last_received_ts = 76.998093 last_seq = 50 current_ts = 77.017639 current_received_ts = 77.017166 current_seq = 51 count = 46 seq_step = 1 [77.259563] (kms_flip:2123) DEBUG: Vblank took 36us [77.278333] (kms_flip:2123) DEBUG: name = flip last_ts = 77.017639 last_received_ts = 77.017166 last_seq = 51 current_ts = 77.036606 current_received_ts = 77.035980 current_seq = 52 count = 47 seq_step = 1 [77.278400] (kms_flip:2123) DEBUG: Vblank took 47us [77.297422] (kms_flip:2123) DEBUG: name = flip last_ts = 77.036606 last_received_ts = 77.035980 last_seq = 52 current_ts = 77.055550 current_received_ts = 77.055084 current_seq = 53 count = 48 seq_step = 1 [77.297473] (kms_flip:2123) DEBUG: Vblank took 34us [77.316245] (kms_flip:2123) DEBUG: name = flip last_ts = 77.055550 last_received_ts = 77.055084 last_seq = 53 current_ts = 77.074524 current_received_ts = 77.073898 current_seq = 54 count = 49 seq_step = 1 [77.316295] (kms_flip:2123) DEBUG: Vblank took 33us [77.335221] (kms_flip:2123) DEBUG: name = flip last_ts = 77.074524 last_received_ts = 77.073898 last_seq = 54 current_ts = 77.093483 current_received_ts = 77.092857 current_seq = 55 count = 50 seq_step = 1 [77.335273] (kms_flip:2123) DEBUG: Vblank took 34us [77.354267] (kms_flip:2123) DEBUG: name = flip last_ts = 77.093483 last_received_ts = 77.092857 last_seq = 55 current_ts = 77.112442 current_received_ts = 77.111893 current_seq = 56 count = 51 seq_step = 1 [77.354319] (kms_flip:2123) DEBUG: Vblank took 35us [77.373170] (kms_flip:2123) DEBUG: name = flip last_ts = 77.112442 last_received_ts = 77.111893 last_seq = 56 current_ts = 77.131409 current_received_ts = 77.130775 current_seq = 57 count = 52 seq_step = 1 [77.373220] (kms_flip:2123) DEBUG: Vblank took 34us [77.392250] (kms_flip:2123) DEBUG: name = flip last_ts = 77.131409 last_received_ts = 77.130775 last_seq = 57 current_ts = 77.150360 current_received_ts = 77.149841 current_seq = 58 count = 53 seq_step = 1 [77.392301] (kms_flip:2123) DEBUG: Vblank took 34us [77.411104] (kms_flip:2123) DEBUG: name = flip last_ts = 77.150360 last_received_ts = 77.149841 last_seq = 58 current_ts = 77.169319 current_received_ts = 77.168686 current_seq = 59 count = 54 seq_step = 1 [77.411154] (kms_flip:2123) DEBUG: Vblank took 33us [77.430177] (kms_flip:2123) DEBUG: name = flip last_ts = 77.169319 last_received_ts = 77.168686 last_seq = 59 current_ts = 77.188286 current_received_ts = 77.187752 current_seq = 60 count = 55 seq_step = 1 [77.430229] (kms_flip:2123) DEBUG: Vblank took 35us [77.449176] (kms_flip:2123) DEBUG: name = flip last_ts = 77.188286 last_received_ts = 77.187752 last_seq = 60 current_ts = 77.207237 current_received_ts = 77.206734 current_seq = 61 count = 56 seq_step = 1 [77.449228] (kms_flip:2123) DEBUG: Vblank took 35us [77.468040] (kms_flip:2123) DEBUG: name = flip last_ts = 77.207237 last_received_ts = 77.206734 last_seq = 61 current_ts = 77.226212 current_received_ts = 77.225586 current_seq = 62 count = 57 seq_step = 1 [77.468090] (kms_flip:2123) DEBUG: Vblank took 34us [77.487012] (kms_flip:2123) DEBUG: name = flip last_ts = 77.226212 last_received_ts = 77.225586 last_seq = 62 current_ts = 77.245171 current_received_ts = 77.244545 current_seq = 63 count = 58 seq_step = 1 [77.487061] (kms_flip:2123) DEBUG: Vblank took 33us [77.505980] (kms_flip:2123) DEBUG: name = flip last_ts = 77.245171 last_received_ts = 77.244545 last_seq = 63 current_ts = 77.264130 current_received_ts = 77.263504 current_seq = 64 count = 59 seq_step = 1 [77.506031] (kms_flip:2123) DEBUG: Vblank took 35us [77.524960] (kms_flip:2123) DEBUG: name = flip last_ts = 77.264130 last_received_ts = 77.263504 last_seq = 64 current_ts = 77.283096 current_received_ts = 77.282471 current_seq = 65 count = 60 seq_step = 1 [77.525011] (kms_flip:2123) DEBUG: Vblank took 34us [77.543975] (kms_flip:2123) DEBUG: name = flip last_ts = 77.283096 last_received_ts = 77.282471 last_seq = 65 current_ts = 77.302040 current_received_ts = 77.301468 current_seq = 66 count = 61 seq_step = 1 [77.544025] (kms_flip:2123) DEBUG: Vblank took 34us [77.562887] (kms_flip:2123) DEBUG: name = flip last_ts = 77.302040 last_received_ts = 77.301468 last_seq = 66 current_ts = 77.321007 current_received_ts = 77.320374 current_seq = 67 count = 62 seq_step = 1 [77.562937] (kms_flip:2123) DEBUG: Vblank took 33us [77.581870] (kms_flip:2123) DEBUG: name = flip last_ts = 77.321007 last_received_ts = 77.320374 last_seq = 67 current_ts = 77.339966 current_received_ts = 77.339340 current_seq = 68 count = 63 seq_step = 1 [77.581920] (kms_flip:2123) DEBUG: Vblank took 33us [77.600931] (kms_flip:2123) DEBUG: name = flip last_ts = 77.339966 last_received_ts = 77.339340 last_seq = 68 current_ts = 77.358925 current_received_ts = 77.358391 current_seq = 69 count = 64 seq_step = 1 [77.600982] (kms_flip:2123) DEBUG: Vblank took 35us [77.619804] (kms_flip:2123) DEBUG: name = flip last_ts = 77.358925 last_received_ts = 77.358391 last_seq = 69 current_ts = 77.377899 current_received_ts = 77.377258 current_seq = 70 count = 65 seq_step = 1 [77.619847] (kms_flip:2123) DEBUG: Vblank took 30us [77.638827] (kms_flip:2123) DEBUG: name = flip last_ts = 77.377899 last_received_ts = 77.377258 last_seq = 70 current_ts = 77.396858 current_received_ts = 77.396263 current_seq = 71 count = 66 seq_step = 1 [77.638875] (kms_flip:2123) DEBUG: Vblank took 33us [77.657763] (kms_flip:2123) DEBUG: name = flip last_ts = 77.396858 last_received_ts = 77.396263 last_seq = 71 current_ts = 77.415817 current_received_ts = 77.415184 current_seq = 72 count = 67 seq_step = 1 [77.657813] (kms_flip:2123) DEBUG: Vblank took 33us [77.676754] (kms_flip:2123) DEBUG: name = flip last_ts = 77.415817 last_received_ts = 77.415184 last_seq = 72 current_ts = 77.434784 current_received_ts = 77.434158 current_seq = 73 count = 68 seq_step = 1 [77.676804] (kms_flip:2123) DEBUG: Vblank took 34us [77.695713] (kms_flip:2123) DEBUG: name = flip last_ts = 77.434784 last_received_ts = 77.434158 last_seq = 73 current_ts = 77.453743 current_received_ts = 77.453117 current_seq = 74 count = 69 seq_step = 1 [77.695760] (kms_flip:2123) DEBUG: Vblank took 32us [77.714706] (kms_flip:2123) DEBUG: name = flip last_ts = 77.453743 last_received_ts = 77.453117 last_seq = 74 current_ts = 77.472702 current_received_ts = 77.472092 current_seq = 75 count = 70 seq_step = 1 [77.714757] (kms_flip:2123) DEBUG: Vblank took 34us [77.733710] (kms_flip:2123) DEBUG: name = flip last_ts = 77.472702 last_received_ts = 77.472092 last_seq = 75 current_ts = 77.491669 current_received_ts = 77.491081 current_seq = 76 count = 71 seq_step = 1 [77.733761] (kms_flip:2123) DEBUG: Vblank took 35us [77.752689] (kms_flip:2123) DEBUG: name = flip last_ts = 77.491669 last_received_ts = 77.491081 last_seq = 76 current_ts = 77.510628 current_received_ts = 77.510002 current_seq = 77 count = 72 seq_step = 1 [77.752741] (kms_flip:2123) DEBUG: Vblank took 34us [77.771608] (kms_flip:2123) DEBUG: name = flip last_ts = 77.510628 last_received_ts = 77.510002 last_seq = 77 current_ts = 77.529587 current_received_ts = 77.528954 current_seq = 78 count = 73 seq_step = 1 [77.772591] (kms_flip:2123) DEBUG: Vblank took 963us [77.772600] (kms_flip:2123) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931: [77.772715] (kms_flip:2123) CRITICAL: Failed assertion: end - start < 500 [77.772765] (kms_flip:2123) CRITICAL: Last errno: 4, Interrupted system call [77.776931] (kms_flip:2123) igt_core-INFO: Stack trace: [77.780558] (kms_flip:2123) igt_core-INFO: #0 ../lib/igt_core.c:2074 __igt_fail_assert() [77.782746] (kms_flip:2123) igt_core-INFO: #1 ../tests/kms_flip.c:926 run_test_on_crtc_set() [77.782863] (kms_flip:2123) igt_core-INFO: #2 ../tests/kms_flip.c:1920 run_test() [77.782948] (kms_flip:2123) igt_core-INFO: #3 ../tests/kms_flip.c:2220 __igt_unique____real_main2105() [77.782999] (kms_flip:2123) igt_core-INFO: #4 ../tests/kms_flip.c:2105 main() [77.787609] (kms_flip:2123) igt_core-INFO: #5 [__libc_init_first+0x8a] [77.789515] (kms_flip:2123) igt_core-INFO: #6 [__libc_start_main+0x8b] [77.789892] (kms_flip:2123) igt_core-INFO: #7 [_start+0x25] **** END **** Dynamic subtest C-HDMI-A2: FAIL (1.539s) Subtest flip-vs-expired-vblank-interruptible: FAIL (15.789s) [77.811365] (kms_flip:2123) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2352: [77.811382] (kms_flip:2123) igt_kms-CRITICAL: Failed assertion: found_it [77.811394] (kms_flip:2123) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor [77.811404] (kms_flip:2123) igt_kms-CRITICAL: DPMS property not found on 400 |
| Dmesg |
<6> [62.032369] Console: switching to colour dummy device 80x25
<6> [62.032430] [IGT] kms_flip: executing
<7> [62.038188] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [62.038862] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [62.039080] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [62.044609] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:390:HDMI-A-1]
<7> [62.044618] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:390:HDMI-A-1]
<7> [62.049043] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:390:HDMI-A-1] disconnected
<7> [62.049455] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:404:DP-1]
<7> [62.049459] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:404:DP-1]
<7> [62.053698] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:404:DP-1] disconnected
<7> [62.054014] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:412:HDMI-A-3]
<7> [62.054018] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:412:HDMI-A-3]
<7> [62.058217] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:412:HDMI-A-3] disconnected
<7> [62.058455] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:390:HDMI-A-1]
<7> [62.058458] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:390:HDMI-A-1]
<7> [62.062683] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:390:HDMI-A-1] disconnected
<7> [62.062695] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:400:HDMI-A-2]
<7> [62.062699] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:400:HDMI-A-2]
<7> [62.088625] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:400:HDMI-A-2] Supported Monitor Refresh rate range is 0 Hz - 0 Hz
<7> [62.088634] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:400:HDMI-A-2] Assigning EDID-1.4 digital sink color depth as 8 bpc.
<7> [62.088638] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:400:HDMI-A-2] ELD monitor fitHeadless4k
<7> [62.088644] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:400:HDMI-A-2] ELD size 36, SAD count 0
<7> [62.088859] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus tc1] NAK for addr: 0040 w(1)
<7> [62.089031] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus tc1] NAK on first message, retry
<7> [62.089414] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus tc1] NAK for addr: 0040 w(1)
<7> [62.089543] i915 0000:00:02.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [62.089897] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "1366x768": 216 268000 1366 1414 1446 1526 768 771 777 814 0x40 0x6 (CLOCK_HIGH)
<7> [62.089905] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080i": 60 74250 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x15 (NO_INTERLACE)
<7> [62.089921] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080i": 60 74176 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x15 (NO_INTERLACE)
<7> [62.089927] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:400:HDMI-A-2] probed modes:
<7> [62.089933] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [62.089936] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "4088x2304": 10 108000 4088 4152 4280 4536 2304 2305 2308 2340 0x40 0x5
<7> [62.089939] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 15 128430 3840 3889 3921 4000 2160 2163 2169 2195 0x40 0xa
<7> [62.089942] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2880x1800": 11 60000 2880 2888 2984 3040 1800 1802 1820 1845 0x40 0xa
<7> [62.089945] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1600": 13 60000 2560 2568 2664 2720 1600 1602 1620 1645 0x40 0xa
<7> [62.089948] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1440": 15 60000 2560 2568 2664 2720 1440 1442 1460 1485 0x40 0xa
<7> [62.089951] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2048x1536": 17 60000 2048 2056 2152 2208 1536 1538 1556 1581 0x40 0xa
<7> [62.089954] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x1200": 60 162000 1600 1664 1856 2160 1200 1201 1204 1250 0x40 0x5
<7> [62.089957] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1680x1050": 60 146250 1680 1784 1960 2240 1050 1053 1059 1089 0x40 0x6
<7> [62.089960] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x900": 60 108000 1600 1624 1704 1800 900 901 904 1000 0x40 0x5
<7> [62.089963] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x1024": 75 135000 1280 1296 1440 1688 1024 1025 1028 1066 0x40 0x5
<7> [62.089966] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1360x768": 60 84963 1366 1431 1567 1776 768 771 781 798 0x0 0x6
<7> [62.089969] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1360x768": 20 25190 1360 1368 1464 1520 768 770 788 813 0x40 0xa
<7> [62.089972] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x800": 60 83500 1280 1352 1480 1680 800 803 809 831 0x40 0x6
<7> [62.089975] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1152x864": 75 108000 1152 1216 1344 1600 864 865 868 900 0x40 0x5
<7> [62.089978] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74250 1280 1390 1430 1650 720 725 730 750 0x40 0x5
<7> [62.089980] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74250 1280 1390 1430 1650 720 725 730 750 0x40 0x5
<7> [62.089983] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74176 1280 1390 1430 1650 720 725 730 750 0x40 0x5
<7> [62.089986] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 75 78750 1024 1040 1136 1312 768 769 772 800 0x40 0x5
<7> [62.089989] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [62.089992] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1440x480": 60 54054 1440 1472 1596 1716 480 489 495 525 0x40 0xa
<7> [62.089994] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1440x480": 60 54000 1440 1472 1596 1716 480 489 495 525 0x40 0xa
<7> [62.089997] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x576": 60 46560 1024 1064 1160 1296 576 579 584 599 0x0 0x6
<7> [62.090000] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "832x624": 75 57284 832 864 928 1152 624 625 628 667 0x40 0xa
<7> [62.090003] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 75 49500 800 816 896 1056 600 601 604 625 0x40 0x5
<7> [62.090006] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5
<7> [62.090008] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 75 31500 640 656 720 840 480 481 484 500 0x40 0xa
<7> [62.090011] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25200 640 656 752 800 480 490 492 525 0x40 0xa
<7> [62.090014] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7> [62.090017] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7> [62.090021] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "720x400": 70 28320 720 738 846 900 400 412 414 449 0x40 0x6
<7> [62.090050] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:404:DP-1]
<7> [62.090055] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:404:DP-1]
<7> [62.094336] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:404:DP-1] disconnected
<7> [62.094350] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:412:HDMI-A-3]
<7> [62.094353] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:412:HDMI-A-3]
<7> [62.098567] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:412:HDMI-A-3] disconnected
<6> [62.102910] [IGT] kms_flip: starting subtest flip-vs-expired-vblank-interruptible
<6> [62.104616] [IGT] kms_flip: starting dynamic subtest A-HDMI-A2
<7> [62.104990] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:415]
<7> [62.105084] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:419]
<7> [62.151460] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [62.151742] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [62.151970] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [62.152112] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [62.152250] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [62.152414] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [62.152558] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [62.152762] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [62.152912] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2360, found 0)
<7> [62.153045] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [62.153182] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2360, found 0)
<7> [62.153325] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [62.153495] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2096, found 0)
<7> [62.153673] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [62.153802] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [62.153931] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [62.154061] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [62.154215] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [62.154414] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [62.154587] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [62.154716] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2360, found 0)
<7> [62.154845] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [62.154974] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2360, found 0)
<7> [62.155102] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [62.155297] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2096, found 0)
<7> [62.155479] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [62.155617] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [62.155750] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [62.155882] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [62.156012] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [62.156171] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [62.156369] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [62.156504] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [62.156635] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [62.156767] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [62.156915] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 139929, found 0)
<7> [62.157104] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 139929, found 0)
<7> [62.157234] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 139929, found 0)
<7> [62.157378] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [62.157508] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:151:pipe A] releasing DPLL 0
<7> [62.157678] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [62.157869] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:151:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [62.158057] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 2016) -> ( 0 - 0), size 2016 -> 0
<7> [62.158188] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:145:cursor A] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0
<7> [62.158333] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [62.158463] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] lines 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [62.158592] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] blocks 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [62.158755] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] min_ddb 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [62.158951] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:151:pipe A] data rate 0 num active planes 0
<7> [62.159087] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 0
<7> [62.159219] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [62.159429] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:151:pipe A] min cdclk: 69965 kHz -> 0 kHz
<7> [62.159647] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 8746 kHz -> 0 kHz
<7> [62.159795] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [62.159925] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 180000 kHz, actual 180000 kHz
<7> [62.160054] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [62.160187] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:151:pipe A] enable: no [modeset]
<7> [62.160399] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [NOFB], visible: no
<7> [62.160545] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [62.160676] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [62.160806] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [62.160935] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [62.161103] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [62.177279] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [62.196675] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_TC1
<7> [62.198653] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:151:pipe A]
<7> [62.199424] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [62.200126] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:35:plane 1A]
<7> [62.201077] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 180000 kHz, VCO 720000 kHz, ref 24000 kHz, bypass 12000 kHz, voltage level 0
<7> [62.201834] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:389:DDI B/PHY B]
<7> [62.202398] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:399:DDI TC1/PHY C]
<7> [62.202877] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:403:DDI TC2/PHY D]
<7> [62.203264] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:405:DP-MST A]
<7> [62.203816] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:406:DP-MST B]
<7> [62.204182] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:407:DP-MST C]
<7> [62.204607] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:400:HDMI-A-2]
<7> [62.205345] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [62.205776] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [62.206279] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:151:pipe A]
<7> [62.208071] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe B]
<7> [62.208317] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]
<7> [62.208515] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [62.208547] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:400:HDMI-A-2]
<7> [62.208879] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:400:HDMI-A-2] Limiting target display pipe bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [62.209106] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [62.209306] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [62.209555] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:399:DDI TC1/PHY C] [CRTC:151:pipe A]
<7> [62.209859] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [62.210070] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [62.210274] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [62.210547] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [62.210868] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [62.211079] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [62.211280] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [62.211866] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2360)
<7> [62.212028] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [62.212168] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2360)
<7> [62.212307] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [62.212490] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2096)
<7> [62.212679] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [62.212824] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [62.212964] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [62.213104] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [62.213299] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [62.213461] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [62.213610] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [62.213748] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2360)
<7> [62.213885] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [62.214084] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2360)
<7> [62.214284] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [62.214440] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2096)
<7> [62.214570] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [62.214717] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [62.214847] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [62.215029] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [62.215188] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [62.215316] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [62.215444] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [62.215572] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [62.215748] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [62.215926] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [62.216056] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 139929)
<7> [62.216184] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 139929)
<7> [62.216312] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 0, found 139929)
<7> [62.216445] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [62.216678] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [62.216816] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:151:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x1
<7> [62.216966] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 2016), size 0 -> 2016
<7> [62.217093] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:145:cursor A] ddb ( 0 - 0) -> (2016 - 2048), size 0 -> 32
<7> [62.217221] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [62.217405] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0
<7> [62.217580] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0
<7> [62.217731] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0
<7> [62.217871] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:151:pipe A] data rate 559716 num active planes 1
<7> [62.218007] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 560
<7> [62.218141] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [62.218339] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:151:pipe A] min cdclk: 0 kHz -> 69965 kHz
<7> [62.218515] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 8746 kHz
<7> [62.218662] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:151:pipe A] allocated DPLL 0
<7> [62.218800] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:151:pipe A] reserving DPLL 0
<7> [62.218937] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:151:pipe A] enable: yes [modeset]
<7> [62.219120] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [62.219278] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [62.219410] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [62.219540] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [62.219745] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [62.219943] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [62.220075] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [62.220203] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [62.220330] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [62.220458] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 42, guardband: 44 vsync start: 0, vsync end: 0
<7> [62.220647] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1081, vmax vblank: 1081, vmin vtotal: 1125, vmax vtotal: 1125
<7> [62.220840] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [62.220974] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [62.221103] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [62.221231] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139929, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [62.221361] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 53 139929 1920 2008 2096 2360 1080 1084 1089 1125 0x40 0x5
<7> [62.221534] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139929, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [62.221720] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 139929, pixel rate 139929, min cdclk 69965, min voltage level 0
<7> [62.221851] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0
<7> [62.221980] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [62.222110] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [62.222272] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [62.222446] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [62.222575] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [62.222718] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1a6655d, cfgcr1: 0xe84, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [62.222847] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [62.222989] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [62.223179] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [62.223308] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [62.223435] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [62.223561] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [62.223699] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [62.223885] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [62.224056] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [62.224188] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [62.224316] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [62.224443] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [62.224571] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [FB:415] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [62.224757] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [62.224950] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [62.225101] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [62.225229] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [62.225356] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [62.225504] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [62.225671] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [62.227454] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [62.227612] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:389:DDI B/PHY B]
<7> [62.227769] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:399:DDI TC1/PHY C]
<7> [62.227951] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:403:DDI TC2/PHY D]
<7> [62.228087] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:405:DP-MST A]
<7> [62.228219] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:406:DP-MST B]
<7> [62.228349] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:407:DP-MST C]
<7> [62.228557] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [62.228839] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:151:pipe A]
<7> [62.228976] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [62.229299] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_TC1
<7> [62.229539] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [62.249003] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [62.249190] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:35:plane 1A]
<7> [62.268379] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:400:HDMI-A-2]
<7> [62.269310] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:151:pipe A]
<6> [66.179993] [IGT] kms_flip: finished subtest A-HDMI-A2, FAIL
<6> [66.180508] [IGT] kms_flip: starting dynamic subtest B-HDMI-A2
<7> [66.180808] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:420]
<7> [66.180892] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:421]
<7> [66.196532] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [66.196613] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [66.196786] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [66.196923] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [66.197056] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [66.197196] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [66.197327] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [66.197457] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [66.197586] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2360, found 0)
<7> [66.197715] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [66.197844] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2360, found 0)
<7> [66.197972] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [66.198100] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2096, found 0)
<7> [66.198277] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [66.198407] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [66.198535] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [66.198667] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [66.198796] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [66.198925] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [66.199052] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [66.199180] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2360, found 0)
<7> [66.199320] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [66.199447] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2360, found 0)
<7> [66.199575] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [66.199702] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2096, found 0)
<7> [66.199831] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [66.199963] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [66.200094] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [66.200230] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [66.200359] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [66.200487] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [66.200616] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [66.200743] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [66.200871] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [66.201000] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [66.201127] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 139929, found 0)
<7> [66.201264] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 139929, found 0)
<7> [66.201419] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 139929, found 0)
<7> [66.201548] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [66.201677] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:151:pipe A] releasing DPLL 0
<7> [66.201834] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [66.201964] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:151:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [66.202111] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 2016) -> ( 0 - 0), size 2016 -> 0
<7> [66.202292] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:145:cursor A] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0
<7> [66.202427] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [66.202556] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] lines 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [66.202684] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] blocks 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [66.202811] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] min_ddb 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [66.202952] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:151:pipe A] data rate 0 num active planes 0
<7> [66.203086] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 0
<7> [66.203228] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [66.203404] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:151:pipe A] min cdclk: 69965 kHz -> 0 kHz
<7> [66.203550] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 8746 kHz -> 0 kHz
<7> [66.203684] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:151:pipe A] enable: no [modeset]
<7> [66.203817] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [NOFB], visible: no
<7> [66.203949] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [66.204080] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [66.204219] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [66.204367] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [66.204496] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [66.214573] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [66.235370] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_TC1
<7> [66.235534] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:151:pipe A]
<7> [66.235694] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [66.235840] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:35:plane 1A]
<7> [66.235981] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:389:DDI B/PHY B]
<7> [66.236115] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:399:DDI TC1/PHY C]
<7> [66.236258] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:403:DDI TC2/PHY D]
<7> [66.236401] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:405:DP-MST A]
<7> [66.236555] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:406:DP-MST B]
<7> [66.236686] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:407:DP-MST C]
<7> [66.236816] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:400:HDMI-A-2]
<7> [66.237008] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [66.237186] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [66.237358] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:151:pipe A]
<7> [66.237692] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe B]
<7> [66.237797] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]
<7> [66.237883] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe B]
<7> [66.237902] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:400:HDMI-A-2]
<7> [66.237973] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:400:HDMI-A-2] Limiting target display pipe bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [66.238122] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [66.238249] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:269:pipe B] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [66.238400] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:399:DDI TC1/PHY C] [CRTC:269:pipe B]
<7> [66.238685] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.enable (expected no, found yes)
<7> [66.238822] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.active (expected no, found yes)
<7> [66.238957] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in cpu_transcoder (expected -1, found 1)
<7> [66.239092] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in lane_count (expected 0, found 4)
<7> [66.239225] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [66.239369] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [66.239519] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [66.239651] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2360)
<7> [66.239779] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [66.239909] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2360)
<7> [66.240037] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [66.240164] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2096)
<7> [66.240292] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [66.240437] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [66.240605] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [66.240733] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [66.240862] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [66.240990] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [66.241118] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [66.241246] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2360)
<7> [66.241389] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [66.241537] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2360)
<7> [66.241665] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [66.241792] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2096)
<7> [66.241919] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [66.242047] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [66.242175] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [66.242302] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [66.242445] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [66.242594] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [66.242721] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [66.242850] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [66.242977] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [66.243106] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in dpll_hw_state
<7> [66.243233] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [66.243370] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: cfgcr0: 0x0, cfgcr1: 0x0, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [66.243525] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [66.243660] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: cfgcr0: 0x1a6655d, cfgcr1: 0xe84, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [66.243793] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [66.243924] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 139929)
<7> [66.244053] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 139929)
<7> [66.244182] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in port_clock (expected 0, found 139929)
<7> [66.244311] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:269:pipe B] fastset requirement not met, forcing full modeset
<7> [66.244483] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [66.244626] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:269:pipe B] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x2
<7> [66.244778] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:153:plane 1B] ddb ( 0 - 0) -> ( 0 - 2016), size 0 -> 2016
<7> [66.244906] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:263:cursor B] ddb ( 0 - 0) -> (2016 - 2048), size 0 -> 32
<7> [66.245033] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:153:plane 1B] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [66.245160] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:153:plane 1B] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0
<7> [66.245287] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:153:plane 1B] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0
<7> [66.245424] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:153:plane 1B] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0
<7> [66.245587] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:269:pipe B] data rate 559716 num active planes 1
<7> [66.245720] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 560
<7> [66.245851] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [66.245993] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:269:pipe B] min cdclk: 0 kHz -> 69965 kHz
<7> [66.246134] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 8746 kHz
<7> [66.246266] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:269:pipe B] allocated DPLL 0
<7> [66.246414] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:269:pipe B] reserving DPLL 0
<7> [66.246575] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:269:pipe B] enable: yes [modeset]
<7> [66.246708] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [66.246841] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: B, pipe bpp: 24, dithering: 0
<7> [66.246972] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [66.247102] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [66.247233] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [66.247369] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [66.247522] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [66.247654] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [66.247785] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [66.247914] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 42, guardband: 44 vsync start: 0, vsync end: 0
<7> [66.248042] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1081, vmax vblank: 1081, vmin vtotal: 1125, vmax vtotal: 1125
<7> [66.248169] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [66.248297] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [66.248441] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [66.248588] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139929, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [66.248717] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 53 139929 1920 2008 2096 2360 1080 1084 1089 1125 0x40 0x5
<7> [66.248844] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139929, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [66.248972] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 139929, pixel rate 139929, min cdclk 69965, min voltage level 0
<7> [66.249097] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0
<7> [66.249223] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [66.249357] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [66.249509] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [66.249636] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [66.249763] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [66.249889] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1a6655d, cfgcr1: 0xe84, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [66.250016] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [66.250143] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [66.250269] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [66.250408] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [66.250557] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [66.250684] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [66.250810] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [66.250938] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [66.251063] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [66.251189] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [66.251315] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [66.251453] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [66.251606] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:153:plane 1B] fb: [FB:420] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [66.251738] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [66.251869] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [66.251999] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:183:plane 2B] fb: [NOFB], visible: no
<7> [66.252126] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:213:plane 3B] fb: [NOFB], visible: no
<7> [66.252253] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:243:plane 4B] fb: [NOFB], visible: no
<7> [66.252390] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:253:plane 5B] fb: [NOFB], visible: no
<7> [66.252545] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:263:cursor B] fb: [NOFB], visible: no
<7> [66.253136] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [66.253296] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:389:DDI B/PHY B]
<7> [66.253504] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:399:DDI TC1/PHY C]
<7> [66.253636] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:403:DDI TC2/PHY D]
<7> [66.253767] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:405:DP-MST A]
<7> [66.253898] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:406:DP-MST B]
<7> [66.254028] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:407:DP-MST C]
<7> [66.254192] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [66.254360] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x2, on? 0) for [CRTC:269:pipe B]
<7> [66.254514] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [66.254866] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_TC1
<7> [66.255040] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe B
<7> [66.293913] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:400:HDMI-A-2]
<7> [66.294129] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:269:pipe B]
<6> [66.418359] pcieport 0000:00:06.0: AER: Correctable error message received from 0000:01:00.0
<4> [66.418379] nvme 0000:01:00.0: PCIe Bus Error: severity=Correctable, type=Physical Layer, (Receiver ID)
<4> [66.418387] nvme 0000:01:00.0: device [15b7:5006] error status/mask=00000001/0000e000
<4> [66.418395] nvme 0000:01:00.0: [ 0] RxErr (First)
<7> [76.331308] i915 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:421] from all active usage due to RMFB ioctl
<7> [76.332226] i915 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:153:plane 1B] because [FB:421] is removed
<7> [76.332469] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:153:plane 1B] ddb ( 0 - 2016) -> ( 0 - 0), size 2016 -> 0
<7> [76.333514] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:153:plane 1B] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [76.335786] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:153:plane 1B] lines 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [76.336690] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:153:plane 1B] blocks 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [76.337723] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:153:plane 1B] min_ddb 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [76.339542] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:269:pipe B] data rate 0 num active planes 0
<7> [76.340582] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 0
<7> [76.341590] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<6> [76.351848] [IGT] kms_flip: finished subtest B-HDMI-A2, SUCCESS
<6> [76.352961] [IGT] kms_flip: starting dynamic subtest C-HDMI-A2
<7> [76.353503] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:417]
<7> [76.353674] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:420]
<7> [76.370873] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [76.371013] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe B]
<7> [76.371072] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [76.371244] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [76.371380] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [76.371511] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [76.371645] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [76.371775] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [76.371940] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [76.372071] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2360, found 0)
<7> [76.372202] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [76.372332] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2360, found 0)
<7> [76.372463] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [76.372593] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2096, found 0)
<7> [76.372722] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [76.372892] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [76.373026] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [76.373155] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [76.373285] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [76.373414] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [76.373543] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [76.373671] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2360, found 0)
<7> [76.373805] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [76.373988] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2360, found 0)
<7> [76.374116] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [76.374244] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2096, found 0)
<7> [76.374374] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [76.374503] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [76.374632] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [76.374760] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [76.374906] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [76.375035] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [76.375164] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [76.375294] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [76.375423] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [76.375554] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [76.375682] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 139929, found 0)
<7> [76.375815] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 139929, found 0)
<7> [76.375944] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:269:pipe B] fastset requirement not met in port_clock (expected 139929, found 0)
<7> [76.376073] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:269:pipe B] fastset requirement not met, forcing full modeset
<7> [76.376203] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:269:pipe B] releasing DPLL 0
<7> [76.376355] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [76.376487] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:269:pipe B] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [76.376636] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:263:cursor B] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0
<7> [76.376783] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:269:pipe B] min cdclk: 69965 kHz -> 0 kHz
<7> [76.376932] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:269:pipe B] enable: no [modeset]
<7> [76.377066] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:153:plane 1B] fb: [NOFB], visible: no
<7> [76.377198] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:183:plane 2B] fb: [NOFB], visible: no
<7> [76.377331] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:213:plane 3B] fb: [NOFB], visible: no
<7> [76.377462] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:243:plane 4B] fb: [NOFB], visible: no
<7> [76.377591] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:253:plane 5B] fb: [NOFB], visible: no
<7> [76.377719] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:263:cursor B] fb: [NOFB], visible: no
<7> [76.377946] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe B
<7> [76.389064] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_TC1
<7> [76.389229] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x2, on? 1) for [CRTC:269:pipe B]
<7> [76.389390] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [76.389537] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:389:DDI B/PHY B]
<7> [76.389674] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:399:DDI TC1/PHY C]
<7> [76.389834] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:403:DDI TC2/PHY D]
<7> [76.389965] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:405:DP-MST A]
<7> [76.390095] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:406:DP-MST B]
<7> [76.390226] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:407:DP-MST C]
<7> [76.390355] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:400:HDMI-A-2]
<7> [76.390546] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [76.390722] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [76.390900] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:269:pipe B]
<7> [76.391199] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]
<7> [76.391326] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]
<7> [76.391345] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:400:HDMI-A-2]
<7> [76.391398] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:400:HDMI-A-2] Limiting target display pipe bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [76.391538] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [76.391664] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:387:pipe C] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [76.391808] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:399:DDI TC1/PHY C] [CRTC:387:pipe C]
<7> [76.391942] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.enable (expected no, found yes)
<7> [76.392078] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.active (expected no, found yes)
<7> [76.392210] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2)
<7> [76.392342] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in lane_count (expected 0, found 4)
<7> [76.392472] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [76.392603] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [76.392733] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [76.392879] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2360)
<7> [76.393010] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [76.393139] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2360)
<7> [76.393269] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [76.393397] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2096)
<7> [76.393526] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [76.393654] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [76.393783] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [76.393928] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [76.394057] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [76.394193] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [76.394321] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [76.394449] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2360)
<7> [76.394578] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [76.394705] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2360)
<7> [76.394847] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [76.394976] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2096)
<7> [76.395103] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [76.395230] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [76.395358] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [76.395485] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [76.395614] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [76.395742] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [76.395883] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [76.396011] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [76.396140] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [76.396268] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in dpll_hw_state
<7> [76.396395] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [76.396523] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: cfgcr0: 0x0, cfgcr1: 0x0, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [76.396652] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [76.396780] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: cfgcr0: 0x1a6655d, cfgcr1: 0xe84, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [76.396919] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [76.397047] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 139929)
<7> [76.397175] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 139929)
<7> [76.397302] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in port_clock (expected 0, found 139929)
<7> [76.397430] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:387:pipe C] fastset requirement not met, forcing full modeset
<7> [76.397577] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [76.397707] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:387:pipe C] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x4
<7> [76.397870] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:271:plane 1C] ddb ( 0 - 0) -> ( 0 - 2016), size 0 -> 2016
<7> [76.397999] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:381:cursor C] ddb ( 0 - 0) -> (2016 - 2048), size 0 -> 32
<7> [76.398126] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [76.398253] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0
<7> [76.398380] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0
<7> [76.398507] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0
<7> [76.398650] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:387:pipe C] data rate 559716 num active planes 1
<7> [76.398786] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 560
<7> [76.398931] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [76.399076] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:387:pipe C] min cdclk: 0 kHz -> 69965 kHz
<7> [76.399220] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:387:pipe C] allocated DPLL 0
<7> [76.399357] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:387:pipe C] reserving DPLL 0
<7> [76.399492] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:387:pipe C] enable: yes [modeset]
<7> [76.399626] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [76.399758] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: C, pipe bpp: 24, dithering: 0
<7> [76.399898] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [76.400027] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [76.400157] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [76.400285] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [76.400414] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [76.400543] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [76.400673] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [76.400800] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 42, guardband: 44 vsync start: 0, vsync end: 0
<7> [76.400941] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1081, vmax vblank: 1081, vmin vtotal: 1125, vmax vtotal: 1125
<7> [76.401068] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [76.401196] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [76.401326] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [76.401454] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139929, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [76.401583] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 53 139929 1920 2008 2096 2360 1080 1084 1089 1125 0x40 0x5
<7> [76.401711] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139929, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [76.401848] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 139929, pixel rate 139929, min cdclk 69965, min voltage level 0
<7> [76.401979] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0
<7> [76.402107] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [76.402235] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [76.402362] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [76.402490] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [76.402618] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [76.402746] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1a6655d, cfgcr1: 0xe84, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [76.402884] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [76.403012] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [76.403138] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [76.403265] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [76.403393] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [76.403520] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [76.403647] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [76.403773] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [76.403909] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [76.404036] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [76.404163] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [76.404290] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [76.404417] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:271:plane 1C] fb: [FB:417] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [76.404547] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [76.404673] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [76.404801] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:301:plane 2C] fb: [NOFB], visible: no
<7> [76.404940] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:331:plane 3C] fb: [NOFB], visible: no
<7> [76.405067] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:361:plane 4C] fb: [NOFB], visible: no
<7> [76.405193] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:371:plane 5C] fb: [NOFB], visible: no
<7> [76.405320] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:381:cursor C] fb: [NOFB], visible: no
<7> [76.406133] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [76.406291] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_4
<7> [76.406453] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:389:DDI B/PHY B]
<7> [76.406590] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:399:DDI TC1/PHY C]
<7> [76.406724] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:403:DDI TC2/PHY D]
<7> [76.406870] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:405:DP-MST A]
<7> [76.407001] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:406:DP-MST B]
<7> [76.407132] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:407:DP-MST C]
<7> [76.407299] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [76.407467] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x4, on? 0) for [CRTC:387:pipe C]
<7> [76.407603] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [76.407795] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_TC1
<7> [76.407980] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe C
<7> [76.447078] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:400:HDMI-A-2]
<7> [76.447895] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:387:pipe C]
<6> [77.891639] [IGT] kms_flip: finished subtest C-HDMI-A2, FAIL
<6> [77.892530] [IGT] kms_flip: finished subtest flip-vs-expired-vblank-interruptible, FAIL
<7> [77.895555] i915 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:420] from all active usage due to RMFB ioctl
<7> [77.895709] i915 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:271:plane 1C] because [FB:420] is removed
<7> [77.895749] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:271:plane 1C] ddb ( 0 - 2016) -> ( 0 - 0), size 2016 -> 0
<7> [77.896066] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [77.896209] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] lines 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [77.896346] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] blocks 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [77.896482] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:271:plane 1C] min_ddb 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [77.896640] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:387:pipe C] data rate 0 num active planes 0
<7> [77.896781] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 0
<7> [77.896927] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<6> [77.909459] [IGT] kms_flip: exiting, ret=98
<7> [77.920168] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:400:HDMI-A-2] Limiting target display pipe bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [77.920355] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [77.920489] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [77.920641] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:399:DDI TC1/PHY C] [CRTC:151:pipe A]
<7> [77.920772] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [77.920927] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [77.921078] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [77.921212] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [77.921344] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [77.921476] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [77.921606] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [77.921738] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2360)
<7> [77.921868] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [77.922014] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2360)
<7> [77.922158] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [77.922289] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2096)
<7> [77.922419] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [77.922549] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [77.922679] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [77.922808] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [77.922957] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [77.923097] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [77.923226] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [77.923355] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2360)
<7> [77.923484] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [77.923612] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2360)
<7> [77.923741] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [77.923869] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2096)
<7> [77.924118] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [77.924249] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [77.924379] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [77.924508] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [77.924637] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [77.924765] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [77.924895] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [77.925045] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [77.925187] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [77.925316] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [77.925445] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 139929)
<7> [77.925574] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 139929)
<7> [77.925703] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 0, found 139929)
<7> [77.925832] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [77.925978] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.enable (expected yes, found no)
<7> [77.926124] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.active (expected yes, found no)
<7> [77.926256] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in cpu_transcoder (expected 2, found -1)
<7> [77.926388] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in lane_count (expected 4, found 0)
<7> [77.926519] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [77.926650] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [77.926781] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [77.926911] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2360, found 0)
<7> [77.927063] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [77.927205] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2360, found 0)
<7> [77.927334] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [77.927462] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2096, found 0)
<7> [77.927608] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [77.927737] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [77.927866] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [77.928010] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [77.928152] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [77.928280] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [77.928407] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [77.928535] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2360, found 0)
<7> [77.928663] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [77.928790] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2360, found 0)
<7> [77.928927] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [77.929069] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2096, found 0)
<7> [77.929197] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [77.929325] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [77.929452] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [77.929579] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [77.929707] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [77.929835] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [77.930056] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [77.930185] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [77.930313] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [77.930443] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [77.930570] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 139929, found 0)
<7> [77.930698] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 139929, found 0)
<7> [77.930827] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe C] fastset requirement not met in port_clock (expected 139929, found 0)
<7> [77.931010] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:387:pipe C] fastset requirement not met, forcing full modeset
<7> [77.931205] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:387:pipe C] releasing DPLL 0
<7> [77.931450] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:151:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x4 -> 0x1
<7> [77.931644] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:387:pipe C] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x4 -> 0x1
<7> [77.931833] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 2016), size 0 -> 2016
<7> [77.932030] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:145:cursor A] ddb ( 0 - 0) -> (2016 - 2048), size 0 -> 32
<7> [77.932162] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [77.932293] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0
<7> [77.932419] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0
<7> [77.932545] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0
<7> [77.932670] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:381:cursor C] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0
<7> [77.932815] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:151:pipe A] data rate 559716 num active planes 1
<7> [77.932962] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 560
<7> [77.933093] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [77.933239] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:151:pipe A] min cdclk: 0 kHz -> 69965 kHz
<7> [77.933371] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:387:pipe C] min cdclk: 69965 kHz -> 0 kHz
<7> [77.933517] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:151:pipe A] allocated DPLL 0
<7> [77.933653] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:151:pipe A] reserving DPLL 0
<7> [77.933792] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:151:pipe A] enable: yes [modeset]
<7> [77.933935] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [77.934068] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [77.934199] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [77.934328] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [77.934458] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [77.934587] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [77.934717] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [77.934846] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [77.934993] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [77.935121] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 42, guardband: 44 vsync start: 0, vsync end: 0
<7> [77.935249] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1081, vmax vblank: 1081, vmin vtotal: 1125, vmax vtotal: 1125
<7> [77.935377] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [77.935504] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [77.935632] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [77.935760] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139929, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [77.935889] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 53 139929 1920 2008 2096 2360 1080 1084 1089 1125 0x40 0x5
<7> [77.936040] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139929, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [77.936168] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 139929, pixel rate 139929, min cdclk 69965, min voltage level 0
<7> [77.936296] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0
<7> [77.936423] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [77.936555] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [77.936686] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [77.936817] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [77.936954] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [77.937083] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1a6655d, cfgcr1: 0xe84, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [77.937212] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [77.937340] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [77.937468] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [77.937595] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [77.937722] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [77.937849] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [77.937987] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [77.938115] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [77.938242] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [77.938368] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [77.938494] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [77.938620] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [77.938746] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [FB:416] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [77.938874] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [77.939011] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [77.939138] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [77.939264] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [77.939391] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [77.939516] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [77.939642] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [77.939768] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:387:pipe C] enable: no [modeset]
<7> [77.939894] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:271:plane 1C] fb: [NOFB], visible: no
<7> [77.940039] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:301:plane 2C] fb: [NOFB], visible: no
<7> [77.940169] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:331:plane 3C] fb: [NOFB], visible: no
<7> [77.940297] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:361:plane 4C] fb: [NOFB], visible: no
<7> [77.940426] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:371:plane 5C] fb: [NOFB], visible: no
<7> [77.940553] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:381:cursor C] fb: [NOFB], visible: no
<7> [77.941044] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe C
<7> [77.946022] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_TC1
<7> [77.946286] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x4, on? 1) for [CRTC:387:pipe C]
<7> [77.946502] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [77.946652] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:389:DDI B/PHY B]
<7> [77.946789] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:399:DDI TC1/PHY C]
<7> [77.946923] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:403:DDI TC2/PHY D]
<7> [77.947076] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:405:DP-MST A]
<7> [77.947207] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:406:DP-MST B]
<7> [77.947336] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:407:DP-MST C]
<7> [77.947598] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:151:pipe A]
<7> [77.947733] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [77.948060] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_TC1
<7> [77.948243] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [77.967729] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [77.967877] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:35:plane 1A]
<7> [77.986793] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:400:HDMI-A-2]
<7> [77.987024] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:151:pipe A]
<7> [77.987570] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_4
<7> [77.987817] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:387:pipe C]
<7> [77.988207] i915 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0
|