Result:
i915_display_info1 igt_runner1 results1.json results1-xe-load.json guc_logs1.tar i915_display_info_post_exec1 boot1 dmesg1
| Detail | Value |
|---|---|
| Duration | 22.24 seconds |
| Hostname |
shard-lnl-8 |
| Igt-Version |
IGT-Version: 2.2-g5d645d459 (x86_64) (Linux: 6.18.0-lgci-xe-xe-4204-e04a4be1a156054fa+ x86_64) |
| Out |
Using IGT_SRANDOM=1765211832 for randomisation Opened device: /dev/dri/card0 Using monotonic timestamps Starting subtest: flip-vs-expired-vblank-interruptible Starting dynamic subtest: A-eDP1 2880x1800: 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa Stack trace: #0 ../lib/igt_core.c:2075 __igt_fail_assert() #1 ../tests/kms_flip.c:923 run_test_on_crtc_set.constprop.0() #2 ../tests/kms_flip.c:1889 run_test() #3 ../tests/kms_flip.c:2158 __igt_unique____real_main2045() #4 ../tests/kms_flip.c:2045 main() #5 [__libc_init_first+0x8a] #6 [__libc_start_main+0x8b] #7 [_start+0x25] Dynamic subtest A-eDP1: FAIL (9.234s) Starting dynamic subtest: B-eDP1 2880x1800: 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa Stack trace: #0 ../lib/igt_core.c:2075 __igt_fail_assert() #1 ../tests/kms_flip.c:923 run_test_on_crtc_set.constprop.0() #2 ../tests/kms_flip.c:1889 run_test() #3 ../tests/kms_flip.c:2158 __igt_unique____real_main2045() #4 ../tests/kms_flip.c:2045 main() #5 [__libc_init_first+0x8a] #6 [__libc_start_main+0x8b] #7 [_start+0x25] Dynamic subtest B-eDP1: FAIL (1.766s) Starting dynamic subtest: C-eDP1 2880x1800: 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa Dynamic subtest C-eDP1: SUCCESS (11.231s) Subtest flip-vs-expired-vblank-interruptible: FAIL (22.243s) |
| Err |
Starting subtest: flip-vs-expired-vblank-interruptible Starting dynamic subtest: A-eDP1 (kms_flip:5373) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:928: (kms_flip:5373) CRITICAL: Failed assertion: end - start < 500 (kms_flip:5373) CRITICAL: Last errno: 4, Interrupted system call Dynamic subtest A-eDP1 failed. **** DEBUG **** (kms_flip:5373) DEBUG: Vblank took 102us (kms_flip:5373) DEBUG: name = flip last_ts = 303.255188 last_received_ts = 303.254425 last_seq = 1585 current_ts = 303.271851 current_received_ts = 303.271057 current_seq = 1586 count = 361 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 50us (kms_flip:5373) DEBUG: name = flip last_ts = 303.271851 last_received_ts = 303.271057 last_seq = 1586 current_ts = 303.288513 current_received_ts = 303.287750 current_seq = 1587 count = 362 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 45us (kms_flip:5373) DEBUG: name = flip last_ts = 303.288513 last_received_ts = 303.287750 last_seq = 1587 current_ts = 303.305176 current_received_ts = 303.304413 current_seq = 1588 count = 363 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 53us (kms_flip:5373) DEBUG: name = flip last_ts = 303.305176 last_received_ts = 303.304413 last_seq = 1588 current_ts = 303.321838 current_received_ts = 303.321075 current_seq = 1589 count = 364 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 60us (kms_flip:5373) DEBUG: name = flip last_ts = 303.321838 last_received_ts = 303.321075 last_seq = 1589 current_ts = 303.338501 current_received_ts = 303.337738 current_seq = 1590 count = 365 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 51us (kms_flip:5373) DEBUG: name = flip last_ts = 303.338501 last_received_ts = 303.337738 last_seq = 1590 current_ts = 303.355194 current_received_ts = 303.354370 current_seq = 1591 count = 366 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 53us (kms_flip:5373) DEBUG: name = flip last_ts = 303.355194 last_received_ts = 303.354370 last_seq = 1591 current_ts = 303.371826 current_received_ts = 303.371063 current_seq = 1592 count = 367 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 44us (kms_flip:5373) DEBUG: name = flip last_ts = 303.371826 last_received_ts = 303.371063 last_seq = 1592 current_ts = 303.388489 current_received_ts = 303.387726 current_seq = 1593 count = 368 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 36us (kms_flip:5373) DEBUG: name = flip last_ts = 303.388489 last_received_ts = 303.387726 last_seq = 1593 current_ts = 303.405182 current_received_ts = 303.404388 current_seq = 1594 count = 369 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 47us (kms_flip:5373) DEBUG: name = flip last_ts = 303.405182 last_received_ts = 303.404388 last_seq = 1594 current_ts = 303.421844 current_received_ts = 303.421051 current_seq = 1595 count = 370 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 52us (kms_flip:5373) DEBUG: name = flip last_ts = 303.421844 last_received_ts = 303.421051 last_seq = 1595 current_ts = 303.438507 current_received_ts = 303.437714 current_seq = 1596 count = 371 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 59us (kms_flip:5373) DEBUG: name = flip last_ts = 303.438507 last_received_ts = 303.437714 last_seq = 1596 current_ts = 303.455170 current_received_ts = 303.454376 current_seq = 1597 count = 372 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 51us (kms_flip:5373) DEBUG: name = flip last_ts = 303.455170 last_received_ts = 303.454376 last_seq = 1597 current_ts = 303.471832 current_received_ts = 303.471039 current_seq = 1598 count = 373 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 49us (kms_flip:5373) DEBUG: name = flip last_ts = 303.471832 last_received_ts = 303.471039 last_seq = 1598 current_ts = 303.488495 current_received_ts = 303.487701 current_seq = 1599 count = 374 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 59us (kms_flip:5373) DEBUG: name = flip last_ts = 303.488495 last_received_ts = 303.487701 last_seq = 1599 current_ts = 303.505157 current_received_ts = 303.504364 current_seq = 1600 count = 375 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 43us (kms_flip:5373) DEBUG: name = flip last_ts = 303.505157 last_received_ts = 303.504364 last_seq = 1600 current_ts = 303.521820 current_received_ts = 303.521057 current_seq = 1601 count = 376 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 46us (kms_flip:5373) DEBUG: name = flip last_ts = 303.521820 last_received_ts = 303.521057 last_seq = 1601 current_ts = 303.538483 current_received_ts = 303.537689 current_seq = 1602 count = 377 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 46us (kms_flip:5373) DEBUG: name = flip last_ts = 303.538483 last_received_ts = 303.537689 last_seq = 1602 current_ts = 303.555145 current_received_ts = 303.554352 current_seq = 1603 count = 378 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 44us (kms_flip:5373) DEBUG: name = flip last_ts = 303.555145 last_received_ts = 303.554352 last_seq = 1603 current_ts = 303.571808 current_received_ts = 303.571045 current_seq = 1604 count = 379 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 43us (kms_flip:5373) DEBUG: name = flip last_ts = 303.571808 last_received_ts = 303.571045 last_seq = 1604 current_ts = 303.588470 current_received_ts = 303.587677 current_seq = 1605 count = 380 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 45us (kms_flip:5373) DEBUG: name = flip last_ts = 303.588470 last_received_ts = 303.587677 last_seq = 1605 current_ts = 303.605133 current_received_ts = 303.604370 current_seq = 1606 count = 381 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 44us (kms_flip:5373) DEBUG: name = flip last_ts = 303.605133 last_received_ts = 303.604370 last_seq = 1606 current_ts = 303.621796 current_received_ts = 303.621033 current_seq = 1607 count = 382 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 51us (kms_flip:5373) DEBUG: name = flip last_ts = 303.621796 last_received_ts = 303.621033 last_seq = 1607 current_ts = 303.638458 current_received_ts = 303.637695 current_seq = 1608 count = 383 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 44us (kms_flip:5373) DEBUG: name = flip last_ts = 303.638458 last_received_ts = 303.637695 last_seq = 1608 current_ts = 303.655121 current_received_ts = 303.654327 current_seq = 1609 count = 384 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 39us (kms_flip:5373) DEBUG: name = flip last_ts = 303.655121 last_received_ts = 303.654327 last_seq = 1609 current_ts = 303.671783 current_received_ts = 303.671021 current_seq = 1610 count = 385 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 118us (kms_flip:5373) DEBUG: name = flip last_ts = 303.671783 last_received_ts = 303.671021 last_seq = 1610 current_ts = 303.688446 current_received_ts = 303.687683 current_seq = 1611 count = 386 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 48us (kms_flip:5373) DEBUG: name = flip last_ts = 303.688446 last_received_ts = 303.687683 last_seq = 1611 current_ts = 303.705109 current_received_ts = 303.704315 current_seq = 1612 count = 387 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 45us (kms_flip:5373) DEBUG: name = flip last_ts = 303.705109 last_received_ts = 303.704315 last_seq = 1612 current_ts = 303.721771 current_received_ts = 303.721039 current_seq = 1613 count = 388 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 43us (kms_flip:5373) DEBUG: name = flip last_ts = 303.721771 last_received_ts = 303.721039 last_seq = 1613 current_ts = 303.738434 current_received_ts = 303.737671 current_seq = 1614 count = 389 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 46us (kms_flip:5373) DEBUG: name = flip last_ts = 303.738434 last_received_ts = 303.737671 last_seq = 1614 current_ts = 303.755127 current_received_ts = 303.754333 current_seq = 1615 count = 390 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 115us (kms_flip:5373) DEBUG: name = flip last_ts = 303.755127 last_received_ts = 303.754333 last_seq = 1615 current_ts = 303.771759 current_received_ts = 303.770996 current_seq = 1616 count = 391 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 49us (kms_flip:5373) DEBUG: name = flip last_ts = 303.771759 last_received_ts = 303.770996 last_seq = 1616 current_ts = 303.788452 current_received_ts = 303.787659 current_seq = 1617 count = 392 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 52us (kms_flip:5373) DEBUG: name = flip last_ts = 303.788452 last_received_ts = 303.787659 last_seq = 1617 current_ts = 303.805115 current_received_ts = 303.804291 current_seq = 1618 count = 393 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 40us (kms_flip:5373) DEBUG: name = flip last_ts = 303.805115 last_received_ts = 303.804291 last_seq = 1618 current_ts = 303.821777 current_received_ts = 303.820984 current_seq = 1619 count = 394 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 64us (kms_flip:5373) DEBUG: name = flip last_ts = 303.821777 last_received_ts = 303.820984 last_seq = 1619 current_ts = 303.838440 current_received_ts = 303.837646 current_seq = 1620 count = 395 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 56us (kms_flip:5373) DEBUG: name = flip last_ts = 303.838440 last_received_ts = 303.837646 last_seq = 1620 current_ts = 303.855103 current_received_ts = 303.854279 current_seq = 1621 count = 396 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 44us (kms_flip:5373) DEBUG: name = flip last_ts = 303.855103 last_received_ts = 303.854279 last_seq = 1621 current_ts = 303.871765 current_received_ts = 303.870972 current_seq = 1622 count = 397 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 63us (kms_flip:5373) DEBUG: name = flip last_ts = 303.871765 last_received_ts = 303.870972 last_seq = 1622 current_ts = 303.888428 current_received_ts = 303.887634 current_seq = 1623 count = 398 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 42us (kms_flip:5373) DEBUG: name = flip last_ts = 303.888428 last_received_ts = 303.887634 last_seq = 1623 current_ts = 303.905090 current_received_ts = 303.904266 current_seq = 1624 count = 399 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 45us (kms_flip:5373) DEBUG: name = flip last_ts = 303.905090 last_received_ts = 303.904266 last_seq = 1624 current_ts = 303.921753 current_received_ts = 303.920959 current_seq = 1625 count = 400 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 76us (kms_flip:5373) DEBUG: name = flip last_ts = 303.921753 last_received_ts = 303.920959 last_seq = 1625 current_ts = 303.938416 current_received_ts = 303.937622 current_seq = 1626 count = 401 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 49us (kms_flip:5373) DEBUG: name = flip last_ts = 303.938416 last_received_ts = 303.937622 last_seq = 1626 current_ts = 303.955078 current_received_ts = 303.954285 current_seq = 1627 count = 402 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 56us (kms_flip:5373) DEBUG: name = flip last_ts = 303.955078 last_received_ts = 303.954285 last_seq = 1627 current_ts = 303.971741 current_received_ts = 303.970947 current_seq = 1628 count = 403 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 53us (kms_flip:5373) DEBUG: name = flip last_ts = 303.971741 last_received_ts = 303.970947 last_seq = 1628 current_ts = 303.988403 current_received_ts = 303.987640 current_seq = 1629 count = 404 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 99us (kms_flip:5373) DEBUG: name = flip last_ts = 303.988403 last_received_ts = 303.987640 last_seq = 1629 current_ts = 304.005066 current_received_ts = 304.004272 current_seq = 1630 count = 405 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 55us (kms_flip:5373) DEBUG: name = flip last_ts = 304.005066 last_received_ts = 304.004272 last_seq = 1630 current_ts = 304.021729 current_received_ts = 304.020966 current_seq = 1631 count = 406 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 57us (kms_flip:5373) DEBUG: name = flip last_ts = 304.021729 last_received_ts = 304.020966 last_seq = 1631 current_ts = 304.038391 current_received_ts = 304.037628 current_seq = 1632 count = 407 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 44us (kms_flip:5373) DEBUG: name = flip last_ts = 304.038391 last_received_ts = 304.037628 last_seq = 1632 current_ts = 304.055054 current_received_ts = 304.054260 current_seq = 1633 count = 408 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 45us (kms_flip:5373) DEBUG: name = flip last_ts = 304.055054 last_received_ts = 304.054260 last_seq = 1633 current_ts = 304.071716 current_received_ts = 304.070953 current_seq = 1634 count = 409 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 47us (kms_flip:5373) DEBUG: name = flip last_ts = 304.071716 last_received_ts = 304.070953 last_seq = 1634 current_ts = 304.088379 current_received_ts = 304.087616 current_seq = 1635 count = 410 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 46us (kms_flip:5373) DEBUG: name = flip last_ts = 304.088379 last_received_ts = 304.087616 last_seq = 1635 current_ts = 304.105042 current_received_ts = 304.104248 current_seq = 1636 count = 411 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 39us (kms_flip:5373) DEBUG: name = flip last_ts = 304.105042 last_received_ts = 304.104248 last_seq = 1636 current_ts = 304.121704 current_received_ts = 304.120941 current_seq = 1637 count = 412 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 46us (kms_flip:5373) DEBUG: name = flip last_ts = 304.121704 last_received_ts = 304.120941 last_seq = 1637 current_ts = 304.138367 current_received_ts = 304.137604 current_seq = 1638 count = 413 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 49us (kms_flip:5373) DEBUG: name = flip last_ts = 304.138367 last_received_ts = 304.137604 last_seq = 1638 current_ts = 304.155060 current_received_ts = 304.154236 current_seq = 1639 count = 414 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 54us (kms_flip:5373) DEBUG: name = flip last_ts = 304.155060 last_received_ts = 304.154236 last_seq = 1639 current_ts = 304.171722 current_received_ts = 304.170898 current_seq = 1640 count = 415 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 53us (kms_flip:5373) DEBUG: name = flip last_ts = 304.171722 last_received_ts = 304.170898 last_seq = 1640 current_ts = 304.188385 current_received_ts = 304.187592 current_seq = 1641 count = 416 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 43us (kms_flip:5373) DEBUG: name = flip last_ts = 304.188385 last_received_ts = 304.187592 last_seq = 1641 current_ts = 304.205048 current_received_ts = 304.204254 current_seq = 1642 count = 417 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 51us (kms_flip:5373) DEBUG: name = flip last_ts = 304.205048 last_received_ts = 304.204254 last_seq = 1642 current_ts = 304.221710 current_received_ts = 304.220947 current_seq = 1643 count = 418 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 50us (kms_flip:5373) DEBUG: name = flip last_ts = 304.221710 last_received_ts = 304.220947 last_seq = 1643 current_ts = 304.238373 current_received_ts = 304.237579 current_seq = 1644 count = 419 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 46us (kms_flip:5373) DEBUG: name = flip last_ts = 304.238373 last_received_ts = 304.237579 last_seq = 1644 current_ts = 304.255035 current_received_ts = 304.254211 current_seq = 1645 count = 420 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 48us (kms_flip:5373) DEBUG: name = flip last_ts = 304.255035 last_received_ts = 304.254211 last_seq = 1645 current_ts = 304.271698 current_received_ts = 304.270874 current_seq = 1646 count = 421 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 46us (kms_flip:5373) DEBUG: name = flip last_ts = 304.271698 last_received_ts = 304.270874 last_seq = 1646 current_ts = 304.288361 current_received_ts = 304.287567 current_seq = 1647 count = 422 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 61us (kms_flip:5373) DEBUG: name = flip last_ts = 304.288361 last_received_ts = 304.287567 last_seq = 1647 current_ts = 304.305023 current_received_ts = 304.304199 current_seq = 1648 count = 423 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 45us (kms_flip:5373) DEBUG: name = flip last_ts = 304.305023 last_received_ts = 304.304199 last_seq = 1648 current_ts = 304.321686 current_received_ts = 304.320892 current_seq = 1649 count = 424 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 45us (kms_flip:5373) DEBUG: name = flip last_ts = 304.321686 last_received_ts = 304.320892 last_seq = 1649 current_ts = 304.338348 current_received_ts = 304.337555 current_seq = 1650 count = 425 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 46us (kms_flip:5373) DEBUG: name = flip last_ts = 304.338348 last_received_ts = 304.337555 last_seq = 1650 current_ts = 304.355011 current_received_ts = 304.354218 current_seq = 1651 count = 426 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 42us (kms_flip:5373) DEBUG: name = flip last_ts = 304.355011 last_received_ts = 304.354218 last_seq = 1651 current_ts = 304.371674 current_received_ts = 304.370911 current_seq = 1652 count = 427 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 45us (kms_flip:5373) DEBUG: name = flip last_ts = 304.371674 last_received_ts = 304.370911 last_seq = 1652 current_ts = 304.388336 current_received_ts = 304.387543 current_seq = 1653 count = 428 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 40us (kms_flip:5373) DEBUG: name = flip last_ts = 304.388336 last_received_ts = 304.387543 last_seq = 1653 current_ts = 304.404999 current_received_ts = 304.404205 current_seq = 1654 count = 429 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 48us (kms_flip:5373) DEBUG: name = flip last_ts = 304.404999 last_received_ts = 304.404205 last_seq = 1654 current_ts = 304.421661 current_received_ts = 304.420898 current_seq = 1655 count = 430 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 43us (kms_flip:5373) DEBUG: name = flip last_ts = 304.421661 last_received_ts = 304.420898 last_seq = 1655 current_ts = 304.438324 current_received_ts = 304.437561 current_seq = 1656 count = 431 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 46us (kms_flip:5373) DEBUG: name = flip last_ts = 304.438324 last_received_ts = 304.437561 last_seq = 1656 current_ts = 304.454987 current_received_ts = 304.454193 current_seq = 1657 count = 432 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 47us (kms_flip:5373) DEBUG: name = flip last_ts = 304.454987 last_received_ts = 304.454193 last_seq = 1657 current_ts = 304.471649 current_received_ts = 304.470886 current_seq = 1658 count = 433 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 43us (kms_flip:5373) DEBUG: name = flip last_ts = 304.471649 last_received_ts = 304.470886 last_seq = 1658 current_ts = 304.488312 current_received_ts = 304.487549 current_seq = 1659 count = 434 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 52us (kms_flip:5373) DEBUG: name = flip last_ts = 304.488312 last_received_ts = 304.487549 last_seq = 1659 current_ts = 304.504974 current_received_ts = 304.504181 current_seq = 1660 count = 435 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 43us (kms_flip:5373) DEBUG: name = flip last_ts = 304.504974 last_received_ts = 304.504181 last_seq = 1660 current_ts = 304.521637 current_received_ts = 304.520874 current_seq = 1661 count = 436 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 121us (kms_flip:5373) DEBUG: name = flip last_ts = 304.521637 last_received_ts = 304.520874 last_seq = 1661 current_ts = 304.538300 current_received_ts = 304.537537 current_seq = 1662 count = 437 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 46us (kms_flip:5373) DEBUG: name = flip last_ts = 304.538300 last_received_ts = 304.537537 last_seq = 1662 current_ts = 304.554993 current_received_ts = 304.554169 current_seq = 1663 count = 438 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 41us (kms_flip:5373) DEBUG: name = flip last_ts = 304.554993 last_received_ts = 304.554169 last_seq = 1663 current_ts = 304.571655 current_received_ts = 304.570862 current_seq = 1664 count = 439 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 44us (kms_flip:5373) DEBUG: name = flip last_ts = 304.571655 last_received_ts = 304.570862 last_seq = 1664 current_ts = 304.588318 current_received_ts = 304.587524 current_seq = 1665 count = 440 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 45us (kms_flip:5373) DEBUG: name = flip last_ts = 304.588318 last_received_ts = 304.587524 last_seq = 1665 current_ts = 304.604980 current_received_ts = 304.604156 current_seq = 1666 count = 441 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 35us (kms_flip:5373) DEBUG: name = flip last_ts = 304.604980 last_received_ts = 304.604156 last_seq = 1666 current_ts = 304.621643 current_received_ts = 304.620819 current_seq = 1667 count = 442 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 44us (kms_flip:5373) DEBUG: name = flip last_ts = 304.621643 last_received_ts = 304.620819 last_seq = 1667 current_ts = 304.638306 current_received_ts = 304.637543 current_seq = 1668 count = 443 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 125us (kms_flip:5373) DEBUG: name = flip last_ts = 304.638306 last_received_ts = 304.637543 last_seq = 1668 current_ts = 304.654968 current_received_ts = 304.654205 current_seq = 1669 count = 444 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 64us (kms_flip:5373) DEBUG: name = flip last_ts = 304.654968 last_received_ts = 304.654205 last_seq = 1669 current_ts = 304.671631 current_received_ts = 304.670868 current_seq = 1670 count = 445 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 137us (kms_flip:5373) DEBUG: name = flip last_ts = 304.671631 last_received_ts = 304.670868 last_seq = 1670 current_ts = 304.688293 current_received_ts = 304.687531 current_seq = 1671 count = 446 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 125us (kms_flip:5373) DEBUG: name = flip last_ts = 304.688293 last_received_ts = 304.687531 last_seq = 1671 current_ts = 304.704956 current_received_ts = 304.704163 current_seq = 1672 count = 447 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 53us (kms_flip:5373) DEBUG: name = flip last_ts = 304.704956 last_received_ts = 304.704163 last_seq = 1672 current_ts = 304.721619 current_received_ts = 304.720825 current_seq = 1673 count = 448 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 100us (kms_flip:5373) DEBUG: name = flip last_ts = 304.721619 last_received_ts = 304.720825 last_seq = 1673 current_ts = 304.738281 current_received_ts = 304.737488 current_seq = 1674 count = 449 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 58us (kms_flip:5373) DEBUG: name = flip last_ts = 304.738281 last_received_ts = 304.737488 last_seq = 1674 current_ts = 304.754944 current_received_ts = 304.754150 current_seq = 1675 count = 450 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 59us (kms_flip:5373) DEBUG: name = flip last_ts = 304.754944 last_received_ts = 304.754150 last_seq = 1675 current_ts = 304.771606 current_received_ts = 304.770813 current_seq = 1676 count = 451 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 110us (kms_flip:5373) DEBUG: name = flip last_ts = 304.771606 last_received_ts = 304.770813 last_seq = 1676 current_ts = 304.788269 current_received_ts = 304.787476 current_seq = 1677 count = 452 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 44us (kms_flip:5373) DEBUG: name = flip last_ts = 304.788269 last_received_ts = 304.787476 last_seq = 1677 current_ts = 304.804932 current_received_ts = 304.804138 current_seq = 1678 count = 453 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 45us (kms_flip:5373) DEBUG: name = flip last_ts = 304.804932 last_received_ts = 304.804138 last_seq = 1678 current_ts = 304.821594 current_received_ts = 304.820831 current_seq = 1679 count = 454 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 47us (kms_flip:5373) DEBUG: name = flip last_ts = 304.821594 last_received_ts = 304.820831 last_seq = 1679 current_ts = 304.838257 current_received_ts = 304.837463 current_seq = 1680 count = 455 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 41us (kms_flip:5373) DEBUG: name = flip last_ts = 304.838257 last_received_ts = 304.837463 last_seq = 1680 current_ts = 304.854919 current_received_ts = 304.854156 current_seq = 1681 count = 456 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 60us (kms_flip:5373) DEBUG: name = flip last_ts = 304.854919 last_received_ts = 304.854156 last_seq = 1681 current_ts = 304.871582 current_received_ts = 304.870789 current_seq = 1682 count = 457 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 50us (kms_flip:5373) DEBUG: name = flip last_ts = 304.871582 last_received_ts = 304.870789 last_seq = 1682 current_ts = 304.888245 current_received_ts = 304.887482 current_seq = 1683 count = 458 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 48us (kms_flip:5373) DEBUG: name = flip last_ts = 304.888245 last_received_ts = 304.887482 last_seq = 1683 current_ts = 304.904907 current_received_ts = 304.904114 current_seq = 1684 count = 459 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 49us (kms_flip:5373) DEBUG: name = flip last_ts = 304.904907 last_received_ts = 304.904114 last_seq = 1684 current_ts = 304.921570 current_received_ts = 304.920807 current_seq = 1685 count = 460 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 46us (kms_flip:5373) DEBUG: name = flip last_ts = 304.921570 last_received_ts = 304.920807 last_seq = 1685 current_ts = 304.938232 current_received_ts = 304.937469 current_seq = 1686 count = 461 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 50us (kms_flip:5373) DEBUG: name = flip last_ts = 304.938232 last_received_ts = 304.937469 last_seq = 1686 current_ts = 304.954926 current_received_ts = 304.954102 current_seq = 1687 count = 462 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 48us (kms_flip:5373) DEBUG: name = flip last_ts = 304.954926 last_received_ts = 304.954102 last_seq = 1687 current_ts = 304.971588 current_received_ts = 304.970795 current_seq = 1688 count = 463 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 43us (kms_flip:5373) DEBUG: name = flip last_ts = 304.971588 last_received_ts = 304.970795 last_seq = 1688 current_ts = 304.988251 current_received_ts = 304.987427 current_seq = 1689 count = 464 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 46us (kms_flip:5373) DEBUG: name = flip last_ts = 304.988251 last_received_ts = 304.987427 last_seq = 1689 current_ts = 305.004913 current_received_ts = 305.004089 current_seq = 1690 count = 465 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 45us (kms_flip:5373) DEBUG: name = flip last_ts = 305.004913 last_received_ts = 305.004089 last_seq = 1690 current_ts = 305.021576 current_received_ts = 305.020782 current_seq = 1691 count = 466 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 130us (kms_flip:5373) DEBUG: name = flip last_ts = 305.021576 last_received_ts = 305.020782 last_seq = 1691 current_ts = 305.038239 current_received_ts = 305.037445 current_seq = 1692 count = 467 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 32us (kms_flip:5373) DEBUG: name = flip last_ts = 305.038239 last_received_ts = 305.037445 last_seq = 1692 current_ts = 305.054901 current_received_ts = 305.054077 current_seq = 1693 count = 468 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 44us (kms_flip:5373) DEBUG: name = flip last_ts = 305.054901 last_received_ts = 305.054077 last_seq = 1693 current_ts = 305.071564 current_received_ts = 305.070770 current_seq = 1694 count = 469 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 52us (kms_flip:5373) DEBUG: name = flip last_ts = 305.071564 last_received_ts = 305.070770 last_seq = 1694 current_ts = 305.088226 current_received_ts = 305.087433 current_seq = 1695 count = 470 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 47us (kms_flip:5373) DEBUG: name = flip last_ts = 305.088226 last_received_ts = 305.087433 last_seq = 1695 current_ts = 305.104889 current_received_ts = 305.104065 current_seq = 1696 count = 471 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 39us (kms_flip:5373) DEBUG: name = flip last_ts = 305.104889 last_received_ts = 305.104065 last_seq = 1696 current_ts = 305.121552 current_received_ts = 305.120789 current_seq = 1697 count = 472 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 100us (kms_flip:5373) DEBUG: name = flip last_ts = 305.121552 last_received_ts = 305.120789 last_seq = 1697 current_ts = 305.138214 current_received_ts = 305.137421 current_seq = 1698 count = 473 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 44us (kms_flip:5373) DEBUG: name = flip last_ts = 305.138214 last_received_ts = 305.137421 last_seq = 1698 current_ts = 305.154877 current_received_ts = 305.154083 current_seq = 1699 count = 474 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 43us (kms_flip:5373) DEBUG: name = flip last_ts = 305.154877 last_received_ts = 305.154083 last_seq = 1699 current_ts = 305.171539 current_received_ts = 305.170776 current_seq = 1700 count = 475 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 57us (kms_flip:5373) DEBUG: name = flip last_ts = 305.171539 last_received_ts = 305.170776 last_seq = 1700 current_ts = 305.188202 current_received_ts = 305.187408 current_seq = 1701 count = 476 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 44us (kms_flip:5373) DEBUG: name = flip last_ts = 305.188202 last_received_ts = 305.187408 last_seq = 1701 current_ts = 305.204865 current_received_ts = 305.204071 current_seq = 1702 count = 477 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 52us (kms_flip:5373) DEBUG: name = flip last_ts = 305.204865 last_received_ts = 305.204071 last_seq = 1702 current_ts = 305.221527 current_received_ts = 305.220734 current_seq = 1703 count = 478 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 39us (kms_flip:5373) DEBUG: name = flip last_ts = 305.221527 last_received_ts = 305.220734 last_seq = 1703 current_ts = 305.238190 current_received_ts = 305.237396 current_seq = 1704 count = 479 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 51us (kms_flip:5373) DEBUG: name = flip last_ts = 305.238190 last_received_ts = 305.237396 last_seq = 1704 current_ts = 305.254852 current_received_ts = 305.254059 current_seq = 1705 count = 480 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 54us (kms_flip:5373) DEBUG: name = flip last_ts = 305.254852 last_received_ts = 305.254059 last_seq = 1705 current_ts = 305.271515 current_received_ts = 305.270996 current_seq = 1706 count = 481 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 1456us (kms_flip:5373) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:928: (kms_flip:5373) CRITICAL: Failed assertion: end - start < 500 (kms_flip:5373) CRITICAL: Last errno: 4, Interrupted system call (kms_flip:5373) igt_core-INFO: Stack trace: (kms_flip:5373) igt_core-INFO: #0 ../lib/igt_core.c:2075 __igt_fail_assert() (kms_flip:5373) igt_core-INFO: #1 ../tests/kms_flip.c:923 run_test_on_crtc_set.constprop.0() (kms_flip:5373) igt_core-INFO: #2 ../tests/kms_flip.c:1889 run_test() (kms_flip:5373) igt_core-INFO: #3 ../tests/kms_flip.c:2158 __igt_unique____real_main2045() (kms_flip:5373) igt_core-INFO: #4 ../tests/kms_flip.c:2045 main() (kms_flip:5373) igt_core-INFO: #5 [__libc_init_first+0x8a] (kms_flip:5373) igt_core-INFO: #6 [__libc_start_main+0x8b] (kms_flip:5373) igt_core-INFO: #7 [_start+0x25] **** END **** Dynamic subtest A-eDP1: FAIL (9.234s) Starting dynamic subtest: B-eDP1 (kms_flip:5373) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:928: (kms_flip:5373) CRITICAL: Failed assertion: end - start < 500 (kms_flip:5373) CRITICAL: Last errno: 4, Interrupted system call Dynamic subtest B-eDP1 failed. **** DEBUG **** (kms_flip:5373) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=2880, height=1800, format=XR24(0x34325258), modifier=0x100000000000009, size=0) (kms_flip:5373) drmtest-DEBUG: Test requirement passed: is_intel_device(fd) (kms_flip:5373) drmtest-DEBUG: Test requirement passed: is_intel_device(fd) (kms_flip:5373) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=3, pitch=11520) (kms_flip:5373) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd) (kms_flip:5373) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=2880, height=1800, format=XR24(0x34325258), modifier=0x100000000000009, size=0) (kms_flip:5373) drmtest-DEBUG: Test requirement passed: is_intel_device(fd) (kms_flip:5373) drmtest-DEBUG: Test requirement passed: is_intel_device(fd) (kms_flip:5373) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=4, pitch=11520) (kms_flip:5373) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd) (kms_flip:5373) intel_allocator_simple-DEBUG: Using simple allocator (kms_flip:5373) intel_allocator_simple-DEBUG: <ial: 0x5b07c4647670, fd: 5> objects: 3, reserved_areas: 0 (kms_flip:5373) intel_allocator_simple-DEBUG: <ial: 0x5b07c4647670, fd: 5> objects: 3, reserved_areas: 0 (kms_flip:5373) intel_allocator_simple-DEBUG: Using simple allocator (kms_flip:5373) intel_allocator_simple-DEBUG: <ial: 0x5b07c4614b90, fd: 5> objects: 3, reserved_areas: 0 (kms_flip:5373) intel_allocator_simple-DEBUG: <ial: 0x5b07c4614b90, fd: 5> objects: 3, reserved_areas: 0 (kms_flip:5373) intel_allocator_simple-DEBUG: Using simple allocator (kms_flip:5373) intel_allocator_simple-DEBUG: <ial: 0x5b07c4614cd0, fd: 5> objects: 3, reserved_areas: 0 (kms_flip:5373) intel_allocator_simple-DEBUG: <ial: 0x5b07c4614cd0, fd: 5> objects: 3, reserved_areas: 0 (kms_flip:5373) intel_allocator_simple-DEBUG: Using simple allocator (kms_flip:5373) intel_allocator_simple-DEBUG: <ial: 0x5b07c45d19a0, fd: 5> objects: 3, reserved_areas: 0 (kms_flip:5373) intel_allocator_simple-DEBUG: <ial: 0x5b07c45d19a0, fd: 5> objects: 3, reserved_areas: 0 (kms_flip:5373) igt_kms-INFO: 2880x1800: 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa (kms_flip:5373) DEBUG: No stale events found (kms_flip:5373) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0 (kms_flip:5373) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0 (kms_flip:5373) DEBUG: name = flip last_ts = 0.000000 last_received_ts = 0.000000 last_seq = 0 current_ts = 306.527191 current_received_ts = 306.526337 current_seq = 85 count = 0 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 82us (kms_flip:5373) DEBUG: name = flip last_ts = 306.527191 last_received_ts = 306.526337 last_seq = 85 current_ts = 306.543823 current_received_ts = 306.543060 current_seq = 86 count = 1 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 61us (kms_flip:5373) DEBUG: name = flip last_ts = 306.543823 last_received_ts = 306.543060 last_seq = 86 current_ts = 306.560516 current_received_ts = 306.559692 current_seq = 87 count = 2 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 51us (kms_flip:5373) DEBUG: name = flip last_ts = 306.560516 last_received_ts = 306.559692 last_seq = 87 current_ts = 306.577179 current_received_ts = 306.576385 current_seq = 88 count = 3 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 38us (kms_flip:5373) DEBUG: name = flip last_ts = 306.577179 last_received_ts = 306.576385 last_seq = 88 current_ts = 306.593842 current_received_ts = 306.593048 current_seq = 89 count = 4 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 49us (kms_flip:5373) DEBUG: name = flip last_ts = 306.593842 last_received_ts = 306.593048 last_seq = 89 current_ts = 306.610504 current_received_ts = 306.609711 current_seq = 90 count = 5 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 43us (kms_flip:5373) DEBUG: name = flip last_ts = 306.610504 last_received_ts = 306.609711 last_seq = 90 current_ts = 306.627167 current_received_ts = 306.626373 current_seq = 91 count = 6 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 44us (kms_flip:5373) DEBUG: name = flip last_ts = 306.627167 last_received_ts = 306.626373 last_seq = 91 current_ts = 306.643829 current_received_ts = 306.643036 current_seq = 92 count = 7 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 45us (kms_flip:5373) DEBUG: name = flip last_ts = 306.643829 last_received_ts = 306.643036 last_seq = 92 current_ts = 306.660492 current_received_ts = 306.659698 current_seq = 93 count = 8 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 46us (kms_flip:5373) DEBUG: name = flip last_ts = 306.660492 last_received_ts = 306.659698 last_seq = 93 current_ts = 306.677155 current_received_ts = 306.676361 current_seq = 94 count = 9 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 97us (kms_flip:5373) DEBUG: name = flip last_ts = 306.677155 last_received_ts = 306.676361 last_seq = 94 current_ts = 306.693817 current_received_ts = 306.693085 current_seq = 95 count = 10 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 47us (kms_flip:5373) DEBUG: name = flip last_ts = 306.693817 last_received_ts = 306.693085 last_seq = 95 current_ts = 306.710480 current_received_ts = 306.709656 current_seq = 96 count = 11 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 49us (kms_flip:5373) DEBUG: name = flip last_ts = 306.710480 last_received_ts = 306.709656 last_seq = 96 current_ts = 306.727142 current_received_ts = 306.726349 current_seq = 97 count = 12 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 62us (kms_flip:5373) DEBUG: name = flip last_ts = 306.727142 last_received_ts = 306.726349 last_seq = 97 current_ts = 306.743805 current_received_ts = 306.743011 current_seq = 98 count = 13 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 43us (kms_flip:5373) DEBUG: name = flip last_ts = 306.743805 last_received_ts = 306.743011 last_seq = 98 current_ts = 306.760468 current_received_ts = 306.759674 current_seq = 99 count = 14 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 106us (kms_flip:5373) DEBUG: name = flip last_ts = 306.760468 last_received_ts = 306.759674 last_seq = 99 current_ts = 306.777130 current_received_ts = 306.776337 current_seq = 100 count = 15 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 96us (kms_flip:5373) DEBUG: name = flip last_ts = 306.777130 last_received_ts = 306.776337 last_seq = 100 current_ts = 306.793793 current_received_ts = 306.793030 current_seq = 101 count = 16 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 105us (kms_flip:5373) DEBUG: name = flip last_ts = 306.793793 last_received_ts = 306.793030 last_seq = 101 current_ts = 306.810455 current_received_ts = 306.809662 current_seq = 102 count = 17 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 45us (kms_flip:5373) DEBUG: name = flip last_ts = 306.810455 last_received_ts = 306.809662 last_seq = 102 current_ts = 306.827118 current_received_ts = 306.826324 current_seq = 103 count = 18 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 103us (kms_flip:5373) DEBUG: name = flip last_ts = 306.827118 last_received_ts = 306.826324 last_seq = 103 current_ts = 306.843781 current_received_ts = 306.842987 current_seq = 104 count = 19 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 108us (kms_flip:5373) DEBUG: name = flip last_ts = 306.843781 last_received_ts = 306.842987 last_seq = 104 current_ts = 306.860443 current_received_ts = 306.859650 current_seq = 105 count = 20 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 44us (kms_flip:5373) DEBUG: name = flip last_ts = 306.860443 last_received_ts = 306.859650 last_seq = 105 current_ts = 306.877106 current_received_ts = 306.876282 current_seq = 106 count = 21 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 47us (kms_flip:5373) DEBUG: name = flip last_ts = 306.877106 last_received_ts = 306.876282 last_seq = 106 current_ts = 306.893768 current_received_ts = 306.893005 current_seq = 107 count = 22 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 38us (kms_flip:5373) DEBUG: name = flip last_ts = 306.893768 last_received_ts = 306.893005 last_seq = 107 current_ts = 306.910431 current_received_ts = 306.909668 current_seq = 108 count = 23 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 46us (kms_flip:5373) DEBUG: name = flip last_ts = 306.910431 last_received_ts = 306.909668 last_seq = 108 current_ts = 306.927094 current_received_ts = 306.926300 current_seq = 109 count = 24 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 53us (kms_flip:5373) DEBUG: name = flip last_ts = 306.927094 last_received_ts = 306.926300 last_seq = 109 current_ts = 306.943756 current_received_ts = 306.942993 current_seq = 110 count = 25 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 54us (kms_flip:5373) DEBUG: name = flip last_ts = 306.943756 last_received_ts = 306.942993 last_seq = 110 current_ts = 306.960449 current_received_ts = 306.959656 current_seq = 111 count = 26 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 45us (kms_flip:5373) DEBUG: name = flip last_ts = 306.960449 last_received_ts = 306.959656 last_seq = 111 current_ts = 306.977112 current_received_ts = 306.976288 current_seq = 112 count = 27 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 39us (kms_flip:5373) DEBUG: name = flip last_ts = 306.977112 last_received_ts = 306.976288 last_seq = 112 current_ts = 306.993774 current_received_ts = 306.992981 current_seq = 113 count = 28 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 52us (kms_flip:5373) DEBUG: name = flip last_ts = 306.993774 last_received_ts = 306.992981 last_seq = 113 current_ts = 307.010437 current_received_ts = 307.009613 current_seq = 114 count = 29 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 48us (kms_flip:5373) DEBUG: name = flip last_ts = 307.010437 last_received_ts = 307.009613 last_seq = 114 current_ts = 307.027100 current_received_ts = 307.026306 current_seq = 115 count = 30 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 114us (kms_flip:5373) DEBUG: name = flip last_ts = 307.027100 last_received_ts = 307.026306 last_seq = 115 current_ts = 307.043762 current_received_ts = 307.042969 current_seq = 116 count = 31 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 58us (kms_flip:5373) DEBUG: name = flip last_ts = 307.043762 last_received_ts = 307.042969 last_seq = 116 current_ts = 307.060425 current_received_ts = 307.059631 current_seq = 117 count = 32 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 52us (kms_flip:5373) DEBUG: name = flip last_ts = 307.060425 last_received_ts = 307.059631 last_seq = 117 current_ts = 307.077087 current_received_ts = 307.076263 current_seq = 118 count = 33 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 52us (kms_flip:5373) DEBUG: name = flip last_ts = 307.077087 last_received_ts = 307.076263 last_seq = 118 current_ts = 307.093750 current_received_ts = 307.092957 current_seq = 119 count = 34 seq_step = 1 (kms_flip:5373) DEBUG: Vblank took 768us (kms_flip:5373) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:928: (kms_flip:5373) CRITICAL: Failed assertion: end - start < 500 (kms_flip:5373) CRITICAL: Last errno: 4, Interrupted system call (kms_flip:5373) igt_core-INFO: Stack trace: (kms_flip:5373) igt_core-INFO: #0 ../lib/igt_core.c:2075 __igt_fail_assert() (kms_flip:5373) igt_core-INFO: #1 ../tests/kms_flip.c:923 run_test_on_crtc_set.constprop.0() (kms_flip:5373) igt_core-INFO: #2 ../tests/kms_flip.c:1889 run_test() (kms_flip:5373) igt_core-INFO: #3 ../tests/kms_flip.c:2158 __igt_unique____real_main2045() (kms_flip:5373) igt_core-INFO: #4 ../tests/kms_flip.c:2045 main() (kms_flip:5373) igt_core-INFO: #5 [__libc_init_first+0x8a] (kms_flip:5373) igt_core-INFO: #6 [__libc_start_main+0x8b] (kms_flip:5373) igt_core-INFO: #7 [_start+0x25] **** END **** Dynamic subtest B-eDP1: FAIL (1.766s) Starting dynamic subtest: C-eDP1 Dynamic subtest C-eDP1: SUCCESS (11.231s) Subtest flip-vs-expired-vblank-interruptible: FAIL (22.243s) |
| Dmesg |
<6> [296.087644] Console: switching to colour dummy device 80x25
<6> [296.087924] [IGT] kms_flip: executing
<7> [296.095178] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:400:DP-1]
<7> [296.095190] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:400:DP-1]
<7> [296.095400] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port D/TC#1: TC port mode reset (disconnected -> tbt-alt) pin assignment: - max lanes: 4
<7> [296.095552] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:400:DP-1] disconnected
<7> [296.095984] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:412:DP-2]
<7> [296.095990] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:412:DP-2]
<7> [296.096193] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port E/TC#2: TC port mode reset (disconnected -> tbt-alt) pin assignment: - max lanes: 4
<7> [296.096346] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:412:DP-2] disconnected
<7> [296.096724] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:420:DP-3]
<7> [296.096729] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:420:DP-3]
<7> [296.096920] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port F/TC#3: TC port mode reset (disconnected -> tbt-alt) pin assignment: - max lanes: 4
<7> [296.097073] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:420:DP-3] disconnected
<7> [296.097548] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:391:eDP-1]
<7> [296.097552] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:391:eDP-1]
<7> [296.098173] xe 0000:00:02.0: [drm:intel_dp_read_dsc_dpcd [xe]] DSC DPCD: 00 11 00 76 0b 01 01 80 00 01 06 66 08 00 00 00
<7> [296.098617] xe 0000:00:02.0: [drm:intel_dp_print_rates [xe]] source rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 675000, 810000
<7> [296.098955] xe 0000:00:02.0: [drm:intel_dp_print_rates [xe]] sink rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 810000
<7> [296.099268] xe 0000:00:02.0: [drm:intel_dp_print_rates [xe]] common rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 810000
<7> [296.099755] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:391:eDP-1] Supported Monitor Refresh rate range is 40 Hz - 120 Hz
<5> [296.099768] [drm] DisplayID checksum invalid, remainder is 248 (ignoring)
<5> [296.099773] [drm] DisplayID checksum invalid, remainder is 248 (ignoring)
<7> [296.099774] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:391:eDP-1] DisplayID extension version 0x13, primary use 0x00
<7> [296.099778] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:391:eDP-1] Assigning EDID-1.4 digital sink color depth as 10 bpc.
<5> [296.099782] [drm] DisplayID checksum invalid, remainder is 248 (ignoring)
<7> [296.099787] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:391:eDP-1] ELD monitor
<5> [296.099791] [drm] DisplayID checksum invalid, remainder is 248 (ignoring)
<7> [296.099794] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:391:eDP-1] ELD size 20, SAD count 0
<5> [296.099797] [drm] DisplayID checksum invalid, remainder is 248 (ignoring)
<7> [296.099829] xe 0000:00:02.0: [drm:intel_dp_set_edid [xe]] [CONNECTOR:391:eDP-1] VRR capable: yes
<7> [296.100034] xe 0000:00:02.0: [drm:intel_dp_set_edid [xe]] [CONNECTOR:391:eDP-1] DFP max bpc 0, max dotclock 0, TMDS clock 0-0, PCON Max FRL BW 0Gbps
<7> [296.100210] xe 0000:00:02.0: [drm:intel_dp_set_edid [xe]] [CONNECTOR:391:eDP-1] RGB->YcbCr conversion? no, YCbCr 4:2:0 allowed? yes, YCbCr 4:4:4->4:2:0 conversion? no
<5> [296.100871] [drm] DisplayID checksum invalid, remainder is 248 (ignoring)
<5> [296.100876] [drm] DisplayID checksum invalid, remainder is 248 (ignoring)
<5> [296.100878] [drm] DisplayID checksum invalid, remainder is 248 (ignoring)
<7> [296.100977] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:391:eDP-1] probed modes:
<7> [296.100983] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [296.100988] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2880x1800": 120 695310 2880 2928 2960 3040 1800 1803 1809 1906 0x40 0xa
<7> [296.101022] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:400:DP-1]
<7> [296.101027] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:400:DP-1]
<7> [296.101242] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:400:DP-1] disconnected
<7> [296.101260] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:412:DP-2]
<7> [296.101266] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:412:DP-2]
<7> [296.101472] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:412:DP-2] disconnected
<7> [296.101488] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:420:DP-3]
<7> [296.101494] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:420:DP-3]
<7> [296.101844] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:420:DP-3] disconnected
<6> [296.109812] [IGT] kms_flip: starting subtest flip-vs-expired-vblank-interruptible
<6> [296.112034] [IGT] kms_flip: starting dynamic subtest A-eDP1
<7> [296.125308] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:428]
<7> [296.125759] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:429]
<7> [296.174793] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:149:pipe A]
<7> [296.174874] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [296.174995] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [296.175093] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [296.175189] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [296.175282] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in dp_m_n (expected tu 64 data 6329869/8388608 link 421991/524288, found tu 0, data 0/0 link 0/0)
<7> [296.175373] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [296.175462] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [296.175551] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [296.175721] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [296.175845] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [296.175944] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [296.176038] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [296.176131] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [296.176233] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [296.176346] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1800, found 0)
<7> [296.176458] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [296.176569] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [296.176680] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1906, found 0)
<7> [296.176789] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1906, found 0)
<7> [296.176896] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [296.176987] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [296.177076] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [296.177164] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [296.177249] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [296.177332] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [296.177417] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [296.177500] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1800, found 0)
<7> [296.177598] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [296.177697] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [296.177797] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1906, found 0)
<7> [296.177891] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1906, found 0)
<7> [296.177973] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [296.178062] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [296.178160] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [296.178258] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [296.178356] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 347710, found 0)
<7> [296.178437] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 347710, found 0)
<7> [296.178519] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in port_clock (expected 432000, found 0)
<7> [296.178620] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:149:pipe A] fastset requirement not met, forcing full modeset
<7> [296.178724] xe 0000:00:02.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:149:pipe A] releasing DPLL 0
<7> [296.179004] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [296.179085] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:149:pipe A] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [296.179182] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:33:plane 1A] ddb ( 0 - 4051) -> ( 0 - 0), size 4051 -> 0
<7> [296.179250] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:143:cursor A] ddb (4051 - 4096) -> ( 0 - 0), size 45 -> 0
<7> [296.179315] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33: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> [296.179381] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] lines 1, 13, 13, 13, 13, 13, 0, 0, 0, 3, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [296.179445] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] blocks 24, 313, 313, 313, 313, 313, 0, 0, 38, 73, 87 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [296.179509] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] min_ddb 28, 346, 346, 346, 346, 346, 0, 0, 39, 82, 88 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [296.179594] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:149:pipe A] data rate 0 num active planes 0
<7> [296.179718] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 0 qgv_peak_bw: 38400
<7> [296.179830] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 0 qgv_peak_bw: 38400
<7> [296.179926] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [296.180024] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [296.180132] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 0
<7> [296.180239] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:149:pipe A] min cdclk: 173855 kHz -> 0 kHz
<7> [296.180353] xe 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 21732 kHz -> 0 kHz
<7> [296.180453] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [296.180545] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 153600 kHz, actual 153600 kHz
<7> [296.180662] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [296.180784] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:149:pipe A] enable: no [modeset]
<7> [296.180892] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:33:plane 1A] fb: [NOFB], visible: no
<7> [296.180998] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:63:plane 2A] fb: [NOFB], visible: no
<7> [296.181102] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:93:plane 3A] fb: [NOFB], visible: no
<7> [296.181204] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:123:plane 4A] fb: [NOFB], visible: no
<7> [296.181306] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:133:plane 5A] fb: [NOFB], visible: no
<7> [296.181402] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:143:cursor A] fb: [NOFB], visible: no
<7> [296.181568] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [296.181707] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [296.181894] xe 0000:00:02.0: [drm:intel_psr_disable_locked [xe]] Disabling PSR2
<7> [296.182761] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [296.182921] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [296.388802] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:391:eDP-1] set backlight PWM = 0
<7> [296.389361] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe A
<7> [296.400095] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turn panel power off
<7> [296.400459] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [296.400887] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [296.451943] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [296.452277] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:149:pipe A]
<7> [296.453229] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [296.453475] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disabling DPLL 0
<7> [296.453559] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - short
<7> [296.453921] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [296.454300] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:390:DDI A/PHY A]
<7> [296.454288] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [296.454527] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:399:DDI TC1/PHY TC1]
<7> [296.454766] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DP-MST A]
<7> [296.454969] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:402:DP-MST B]
<7> [296.455167] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST C]
<7> [296.455359] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:411:DDI TC2/PHY TC2]
<7> [296.455569] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DP-MST A]
<7> [296.455794] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:414:DP-MST B]
<7> [296.455988] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST C]
<7> [296.456182] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:419:DDI TC3/PHY TC3]
<7> [296.456369] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DP-MST A]
<7> [296.456548] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:422:DP-MST B]
<7> [296.456755] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST C]
<7> [296.456952] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:391:eDP-1]
<7> [296.457203] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [296.457394] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [296.457599] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [296.457973] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_A
<7> [296.458316] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:149:pipe A]
<7> [296.458599] xe 0000:00:02.0: [drm:intel_set_cdclk [xe]] Post changing CDCLK to 153600 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [296.458861] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [296.459065] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=384
<7> [296.459743] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:268:pipe B]
<7> [296.460034] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]
<7> [296.460262] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:149:pipe A]
<7> [296.460317] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:391:eDP-1]
<7> [296.460448] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CONNECTOR:391:eDP-1] Limiting display bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [296.460796] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:390:DDI A/PHY A][CRTC:149:pipe A] DP link limits: pixel clock 347710 kHz DSC off max lanes 4 max rate 810000 max pipe_bpp 30 max link_bpp 30.0000
<7> [296.461084] xe 0000:00:02.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 4 clock 432000 bpp input 30 compressed 0.0000 link rate required 1303913 available 1728000
<7> [296.461373] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:149:pipe A] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [296.461702] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:390:DDI A/PHY A] [CRTC:149:pipe A]
<7> [296.462012] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [296.462376] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [296.462688] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [296.463018] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [296.463304] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6329869/8388608 link 421991/524288)
<7> [296.463582] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [296.463856] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [296.464122] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [296.464437] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [296.464706] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)
<7> [296.464951] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)
<7> [296.465183] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)
<7> [296.465465] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)
<7> [296.465705] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [296.465921] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1800)
<7> [296.466126] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)
<7> [296.466320] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)
<7> [296.466515] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1906)
<7> [296.466722] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1906)
<7> [296.466919] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [296.467097] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [296.467269] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)
<7> [296.467439] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)
<7> [296.467621] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)
<7> [296.467807] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)
<7> [296.467974] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [296.468138] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1800)
<7> [296.468298] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)
<7> [296.468450] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)
<7> [296.468605] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1906)
<7> [296.468751] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1906)
<7> [296.468898] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [296.469041] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [296.469199] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [296.469333] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [296.469466] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 347710)
<7> [296.469599] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 347710)
<7> [296.469725] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in port_clock (expected 0, found 432000)
<7> [296.469850] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:149:pipe A] fastset requirement not met, forcing full modeset
<7> [296.470014] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [296.470113] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:149:pipe A] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x0 -> 0x1
<7> [296.470240] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:33:plane 1A] ddb ( 0 - 0) -> ( 0 - 4051), size 0 -> 4051
<7> [296.470337] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:143:cursor A] ddb ( 0 - 0) -> (4051 - 4096), size 0 -> 45
<7> [296.470429] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33: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> [296.470520] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 13, 13, 13, 13, 13, 0, 0, 0, 4, 0
<7> [296.470665] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 92, 297, 297, 297, 297, 297, 0, 0, 197, 92, 197
<7> [296.470753] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 183, 456, 456, 456, 456, 456, 0, 0, 198, 183, 198
<7> [296.470861] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:149:pipe A] data rate 1390840 num active planes 1
<7> [296.470993] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [296.471114] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [296.471228] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [296.471340] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [296.471451] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [296.471568] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:149:pipe A] min cdclk: 0 kHz -> 173855 kHz
<7> [296.471700] xe 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 0 kHz -> 21732 kHz
<7> [296.471825] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [296.471933] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [296.472031] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [296.472142] xe 0000:00:02.0: [drm:intel_find_dpll [xe]] [CRTC:149:pipe A] allocated DPLL 0
<7> [296.472235] xe 0000:00:02.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:149:pipe A] reserving DPLL 0
<7> [296.472331] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:149:pipe A] enable: yes [modeset]
<7> [296.472430] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [296.472527] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: A, pipe bpp: 30, dithering: 0
<7> [296.472632] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [296.472738] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [296.472841] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [296.472930] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [296.473018] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m_n: lanes: 4; data_m: 6329869, data_n: 8388608, link_m: 421991, link_n: 524288, tu: 64
<7> [296.473112] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m2_n2: lanes: 4; data_m: 0, data_n: 0, link_m: 0, link_n: 0, tu: 0
<7> [296.473201] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [296.473284] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [296.473378] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled
<7> [296.473475] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0
<7> [296.473578] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [296.473678] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 4, length 14
<7> [296.473775] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [296.473869] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [296.473943] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [296.474022] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [296.474110] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [296.474201] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [296.474282] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [296.474365] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: no, fixed rr: yes, vmin: 1906, vmax: 1906, flipline: 1906, pipeline full: 0, guardband: 104 vsync start: 103, vsync end: 97
<7> [296.474451] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1802, vmax vblank: 1802, vmin vtotal: 1906, vmax vtotal: 1906
<7> [296.474533] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] requested mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [296.474636] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [296.474735] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=347710, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1906 vs=1803-1809 vt=1906, flags=0xa
<7> [296.474834] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x40 0xa
<7> [296.474919] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=347710, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1906 vs=1803-1809 vt=1906, flags=0xa
<7> [296.475005] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 432000, pipe src: 2880x1800+0+0, pixel rate 347710, min cdclk 173855
<7> [296.475089] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [296.475171] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [296.475256] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [296.475350] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [296.475446] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: yes, tbt_mode: no
<7> [296.475540] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_hw_state: clock: 432000, fracen: no,
<7> [296.475638] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] multiplier: 225, tx_clk_div: 1.
<7> [296.475732] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_rawhw_state:
<7> [296.475826] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] tx: 0x10, cmn: 0x21
<7> [296.475915] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1
<7> [296.475999] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [296.476079] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [296.476157] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [296.476235] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23
<7> [296.476315] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [296.476399] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [296.476478] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [296.476552] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [296.476658] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [296.476767] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [296.476880] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [296.476971] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [296.477052] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [296.477131] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [296.477209] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [296.477287] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [296.477363] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [296.477436] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:33:plane 1A] fb: [FB:428] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [296.477508] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [296.477591] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [296.477605] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [296.477687] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:63:plane 2A] fb: [NOFB], visible: no
<7> [296.477782] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:93:plane 3A] fb: [NOFB], visible: no
<7> [296.477767] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [296.477875] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:123:plane 4A] fb: [NOFB], visible: no
<7> [296.477902] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [296.477968] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:133:plane 5A] fb: [NOFB], visible: no
<7> [296.478060] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:143:cursor A] fb: [NOFB], visible: no
<7> [296.478486] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [296.478590] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [296.478764] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_A
<7> [296.478941] xe 0000:00:02.0: [drm:intel_set_cdclk [xe]] Pre changing CDCLK to 192000 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [296.479044] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [296.479139] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=480
<7> [296.479230] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:390:DDI A/PHY A]
<7> [296.479306] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:399:DDI TC1/PHY TC1]
<7> [296.479372] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DP-MST A]
<7> [296.479439] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:402:DP-MST B]
<7> [296.479506] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST C]
<7> [296.479586] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:411:DDI TC2/PHY TC2]
<7> [296.479674] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DP-MST A]
<7> [296.479765] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:414:DP-MST B]
<7> [296.479849] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST C]
<7> [296.479922] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:419:DDI TC3/PHY TC3]
<7> [296.479991] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DP-MST A]
<7> [296.480059] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:422:DP-MST B]
<7> [296.480129] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST C]
<7> [296.480221] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [296.480389] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [296.480455] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 8 (mbus joined: yes)
<7> [296.480569] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [296.481598] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:149:pipe A]
<7> [296.481718] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enabling DPLL 0
<7> [296.483431] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turn panel power on
<7> [296.483516] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 wait for panel power cycle (469 ms remaining)
<7> [296.488295] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [296.488438] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - long
<7> [296.488531] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [296.488638] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [296.956903] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [296.987628] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [296.988012] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 wait for panel power on
<7> [296.988369] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [297.024741] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [297.024999] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - long
<7> [297.025210] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [297.025620] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [297.164638] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port F/TC#3: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [297.164644] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port E/TC#2: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [297.164654] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port D/TC#1: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [297.201764] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [297.202183] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turning VDD on
<7> [297.202518] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [297.204601] xe 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX A/DDI A/PHY A: DPCD: 14 1e 44 c1 00 00 01 c0 02 00 02 00 00 0b 80
<7> [297.205864] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [297.206634] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 05
<7> [297.207671] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [297.208742] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [297.209724] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Clock recovery OK
<7> [297.210018] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Using DP training pattern TPS4
<7> [297.211394] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [297.211701] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Link Training passed at link rate = 432000, lane count = 4
<7> [297.212454] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe A
<7> [297.212854] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [297.213145] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe A
<7> [297.213454] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:391:eDP-1] set backlight PWM = 4518
<7> [297.229723] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR2
<7> [297.231412] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:391:eDP-1]
<7> [297.231804] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:149:pipe A]
<7> [299.788644] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turning VDD off
<7> [299.789043] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x00000067
<7> [305.295674] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [305.296088] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [305.296415] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<6> [305.347570] [IGT] kms_flip: finished subtest A-eDP1, FAIL
<6> [305.349864] [IGT] kms_flip: starting dynamic subtest B-eDP1
<7> [305.357098] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:431]
<7> [305.358341] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:432]
<7> [305.412680] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:149:pipe A]
<7> [305.412772] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [305.412935] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [305.413040] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [305.413135] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [305.413229] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in dp_m_n (expected tu 64 data 6329869/8388608 link 421991/524288, found tu 0, data 0/0 link 0/0)
<7> [305.413323] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [305.413414] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [305.413504] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [305.413593] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [305.413754] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [305.413883] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [305.414004] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [305.414097] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [305.414183] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [305.414269] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1800, found 0)
<7> [305.414354] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [305.414437] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [305.414519] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1906, found 0)
<7> [305.414601] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1906, found 0)
<7> [305.414702] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [305.414817] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [305.414920] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [305.415016] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [305.415104] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [305.415192] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [305.415271] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [305.415347] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1800, found 0)
<7> [305.415423] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [305.415499] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [305.415574] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1906, found 0)
<7> [305.415663] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1906, found 0)
<7> [305.415762] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [305.415861] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [305.415954] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [305.416031] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [305.416106] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 347710, found 0)
<7> [305.416181] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 347710, found 0)
<7> [305.416255] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in port_clock (expected 432000, found 0)
<7> [305.416332] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:149:pipe A] fastset requirement not met, forcing full modeset
<7> [305.416412] xe 0000:00:02.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:149:pipe A] releasing DPLL 0
<7> [305.416540] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [305.416605] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:149:pipe A] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [305.416726] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:33:plane 1A] ddb ( 0 - 4051) -> ( 0 - 0), size 4051 -> 0
<7> [305.416819] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:143:cursor A] ddb (4051 - 4096) -> ( 0 - 0), size 45 -> 0
<7> [305.416909] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33: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> [305.416985] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] lines 4, 13, 13, 13, 13, 13, 0, 0, 0, 4, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [305.417054] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] blocks 92, 297, 297, 297, 297, 297, 0, 0, 197, 92, 197 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [305.417112] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] min_ddb 183, 456, 456, 456, 456, 456, 0, 0, 198, 183, 198 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [305.417185] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:149:pipe A] data rate 0 num active planes 0
<7> [305.417278] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 0 qgv_peak_bw: 38400
<7> [305.417373] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 0 qgv_peak_bw: 38400
<7> [305.417460] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [305.417542] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [305.417629] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 0
<7> [305.417732] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:149:pipe A] min cdclk: 173855 kHz -> 0 kHz
<7> [305.417859] xe 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 21732 kHz -> 0 kHz
<7> [305.417976] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [305.418066] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 153600 kHz, actual 153600 kHz
<7> [305.418148] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [305.418253] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:149:pipe A] enable: no [modeset]
<7> [305.418337] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:33:plane 1A] fb: [NOFB], visible: no
<7> [305.418420] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:63:plane 2A] fb: [NOFB], visible: no
<7> [305.418500] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:93:plane 3A] fb: [NOFB], visible: no
<7> [305.418580] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:123:plane 4A] fb: [NOFB], visible: no
<7> [305.418709] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:133:plane 5A] fb: [NOFB], visible: no
<7> [305.418823] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:143:cursor A] fb: [NOFB], visible: no
<7> [305.418997] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [305.419094] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [305.419226] xe 0000:00:02.0: [drm:intel_psr_disable_locked [xe]] Disabling PSR2
<7> [305.419408] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turning VDD on
<7> [305.419512] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006f
<7> [305.420123] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [305.420248] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [305.628754] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:391:eDP-1] set backlight PWM = 0
<7> [305.629368] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe A
<7> [305.640167] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turn panel power off
<7> [305.640506] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [305.640622] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [305.640869] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [305.641092] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - short
<7> [305.693200] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [305.693549] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - short
<7> [305.700702] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [305.701214] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:149:pipe A]
<7> [305.701275] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [305.701875] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [305.702610] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disabling DPLL 0
<7> [305.703206] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [305.703771] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:390:DDI A/PHY A]
<7> [305.704173] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:399:DDI TC1/PHY TC1]
<7> [305.704506] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DP-MST A]
<7> [305.704862] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:402:DP-MST B]
<7> [305.705180] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST C]
<7> [305.705570] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:411:DDI TC2/PHY TC2]
<7> [305.705920] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DP-MST A]
<7> [305.706220] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:414:DP-MST B]
<7> [305.706518] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST C]
<7> [305.706863] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:419:DDI TC3/PHY TC3]
<7> [305.707157] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DP-MST A]
<7> [305.707508] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:422:DP-MST B]
<7> [305.707838] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST C]
<7> [305.708128] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:391:eDP-1]
<7> [305.708512] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [305.708888] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [305.709214] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [305.709782] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_A
<7> [305.710308] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:149:pipe A]
<7> [305.710702] xe 0000:00:02.0: [drm:intel_set_cdclk [xe]] Post changing CDCLK to 153600 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [305.711164] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [305.711509] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=384
<7> [305.712881] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:268:pipe B]
<7> [305.713260] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]
<7> [305.713578] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:268:pipe B]
<7> [305.713658] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:391:eDP-1]
<7> [305.713832] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CONNECTOR:391:eDP-1] Limiting display bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [305.714213] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:390:DDI A/PHY A][CRTC:268:pipe B] DP link limits: pixel clock 347710 kHz DSC off max lanes 4 max rate 810000 max pipe_bpp 30 max link_bpp 30.0000
<7> [305.714535] xe 0000:00:02.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 4 clock 432000 bpp input 30 compressed 0.0000 link rate required 1303913 available 1728000
<7> [305.714892] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:268:pipe B] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [305.715200] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:390:DDI A/PHY A] [CRTC:268:pipe B]
<7> [305.715502] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.enable (expected no, found yes)
<7> [305.715864] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.active (expected no, found yes)
<7> [305.716140] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in cpu_transcoder (expected -1, found 1)
<7> [305.716403] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in lane_count (expected 0, found 4)
<7> [305.716668] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6329869/8388608 link 421991/524288)
<7> [305.716947] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [305.717177] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [305.717396] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [305.717606] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [305.717872] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)
<7> [305.718072] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)
<7> [305.718262] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)
<7> [305.718451] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)
<7> [305.718719] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [305.718894] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1800)
<7> [305.719083] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)
<7> [305.719245] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)
<7> [305.719405] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1906)
<7> [305.719561] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1906)
<7> [305.719729] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [305.719876] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [305.720021] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)
<7> [305.720167] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)
<7> [305.720309] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)
<7> [305.720449] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)
<7> [305.720586] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [305.720730] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1800)
<7> [305.720876] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)
<7> [305.721023] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)
<7> [305.721149] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1906)
<7> [305.721266] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1906)
<7> [305.721383] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [305.721497] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [305.721611] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [305.721738] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [305.721847] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 347710)
<7> [305.721952] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 347710)
<7> [305.722053] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in port_clock (expected 0, found 432000)
<7> [305.722152] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:268:pipe B] fastset requirement not met, forcing full modeset
<7> [305.722283] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [305.722374] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:268:pipe B] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x0 -> 0x2
<7> [305.722501] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:152:plane 1B] ddb ( 0 - 0) -> ( 0 - 4051), size 0 -> 4051
<7> [305.722589] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:262:cursor B] ddb ( 0 - 0) -> (4051 - 4096), size 0 -> 45
<7> [305.722680] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:152: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> [305.722758] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:152:plane 1B] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 13, 13, 13, 13, 13, 0, 0, 0, 4, 0
<7> [305.722834] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:152:plane 1B] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 92, 297, 297, 297, 297, 297, 0, 0, 197, 92, 197
<7> [305.722909] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:152:plane 1B] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 183, 456, 456, 456, 456, 456, 0, 0, 198, 183, 198
<7> [305.722997] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:268:pipe B] data rate 1390840 num active planes 1
<7> [305.723096] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [305.723192] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [305.723285] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [305.723379] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [305.723471] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [305.723558] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:268:pipe B] min cdclk: 0 kHz -> 173855 kHz
<7> [305.723672] xe 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 0 kHz -> 21732 kHz
<7> [305.723790] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [305.723908] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [305.724008] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [305.724106] xe 0000:00:02.0: [drm:intel_find_dpll [xe]] [CRTC:268:pipe B] allocated DPLL 0
<7> [305.724190] xe 0000:00:02.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:268:pipe B] reserving DPLL 0
<7> [305.724278] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:268:pipe B] enable: yes [modeset]
<7> [305.724371] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [305.724464] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: B, pipe bpp: 30, dithering: 0
<7> [305.724547] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [305.724725] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [305.724831] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [305.724933] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [305.725025] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m_n: lanes: 4; data_m: 6329869, data_n: 8388608, link_m: 421991, link_n: 524288, tu: 64
<7> [305.725103] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m2_n2: lanes: 4; data_m: 0, data_n: 0, link_m: 0, link_n: 0, tu: 0
<7> [305.725181] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [305.725262] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [305.725345] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled
<7> [305.725428] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0
<7> [305.725510] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [305.725592] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 4, length 14
<7> [305.725692] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [305.725791] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [305.725889] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [305.725980] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [305.726058] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [305.726134] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [305.726209] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [305.726285] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: no, fixed rr: yes, vmin: 1906, vmax: 1906, flipline: 1906, pipeline full: 0, guardband: 104 vsync start: 103, vsync end: 97
<7> [305.726362] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1802, vmax vblank: 1802, vmin vtotal: 1906, vmax vtotal: 1906
<7> [305.726440] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] requested mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [305.726518] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [305.726596] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=347710, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1906 vs=1803-1809 vt=1906, flags=0xa
<7> [305.726700] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x40 0xa
<7> [305.726800] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=347710, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1906 vs=1803-1809 vt=1906, flags=0xa
<7> [305.726899] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 432000, pipe src: 2880x1800+0+0, pixel rate 347710, min cdclk 173855
<7> [305.726997] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [305.727083] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [305.727164] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [305.727245] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [305.727326] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: yes, tbt_mode: no
<7> [305.727404] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_hw_state: clock: 432000, fracen: no,
<7> [305.727479] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] multiplier: 225, tx_clk_div: 1.
<7> [305.727553] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_rawhw_state:
<7> [305.727632] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] tx: 0x10, cmn: 0x21
<7> [305.727724] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1
<7> [305.727819] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [305.727913] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [305.727996] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [305.728069] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23
<7> [305.728141] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [305.728215] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [305.728157] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [305.728294] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [305.728332] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - long
<7> [305.728373] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [305.728424] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [305.728453] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [305.728533] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [305.728525] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [305.728613] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [305.728723] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [305.728823] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [305.728921] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [305.729011] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [305.729086] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [305.729161] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [305.729235] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:152:plane 1B] fb: [FB:431] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [305.729311] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [305.729385] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [305.729459] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:182:plane 2B] fb: [NOFB], visible: no
<7> [305.729532] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:212:plane 3B] fb: [NOFB], visible: no
<7> [305.729605] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:242:plane 4B] fb: [NOFB], visible: no
<7> [305.729701] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:252:plane 5B] fb: [NOFB], visible: no
<7> [305.729795] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:262:cursor B] fb: [NOFB], visible: no
<7> [305.730180] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [305.730316] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_B
<7> [305.730471] xe 0000:00:02.0: [drm:intel_set_cdclk [xe]] Pre changing CDCLK to 192000 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [305.730571] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [305.730678] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=480
<7> [305.730788] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:390:DDI A/PHY A]
<7> [305.730879] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:399:DDI TC1/PHY TC1]
<7> [305.730959] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DP-MST A]
<7> [305.731026] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:402:DP-MST B]
<7> [305.731092] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST C]
<7> [305.731156] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:411:DDI TC2/PHY TC2]
<7> [305.731220] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DP-MST A]
<7> [305.731282] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:414:DP-MST B]
<7> [305.731344] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST C]
<7> [305.731406] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:419:DDI TC3/PHY TC3]
<7> [305.731467] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DP-MST A]
<7> [305.731527] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:422:DP-MST B]
<7> [305.731590] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST C]
<7> [305.731694] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [305.731883] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [305.731967] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 8 (mbus joined: yes)
<7> [305.732085] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [305.733412] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enable DPLL 0 (active 0x2, on? 0) for [CRTC:268:pipe B]
<7> [305.733508] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enabling DPLL 0
<7> [305.735225] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turn panel power on
<7> [305.735302] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 wait for panel power cycle (466 ms remaining)
<7> [306.204965] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [306.255903] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [306.256241] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 wait for panel power on
<7> [306.256523] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [306.292981] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [306.293395] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - long
<7> [306.293760] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [306.294123] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [306.470300] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [306.470845] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turning VDD on
<7> [306.471251] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [306.473489] xe 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX A/DDI A/PHY A: DPCD: 14 1e 44 c1 00 00 01 c0 02 00 02 00 00 0b 80
<7> [306.474846] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [306.475758] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 05
<7> [306.477745] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [306.479037] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [306.480161] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Clock recovery OK
<7> [306.480528] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Using DP training pattern TPS4
<7> [306.482065] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [306.482435] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Link Training passed at link rate = 432000, lane count = 4
<7> [306.483378] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe B
<7> [306.483896] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [306.484317] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe B
<7> [306.484733] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:391:eDP-1] set backlight PWM = 4518
<7> [306.500800] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR2
<7> [306.502559] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:391:eDP-1]
<7> [306.503001] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:268:pipe B]
<6> [307.116960] [IGT] kms_flip: finished subtest B-eDP1, FAIL
<7> [307.117732] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<6> [307.118083] [IGT] kms_flip: starting dynamic subtest C-eDP1
<7> [307.118046] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [307.118258] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [307.122158] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:433]
<7> [307.135464] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:434]
<7> [307.195395] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:149:pipe A]
<7> [307.195554] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [307.195763] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [307.195967] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:268:pipe B]
<7> [307.196064] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [307.196181] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [307.196292] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [307.196399] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [307.196506] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in dp_m_n (expected tu 64 data 6329869/8388608 link 421991/524288, found tu 0, data 0/0 link 0/0)
<7> [307.196612] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [307.196730] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [307.196837] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [307.196945] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [307.197055] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [307.197168] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [307.197274] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [307.197378] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [307.197485] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [307.197592] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1800, found 0)
<7> [307.197707] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [307.197816] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [307.197921] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1906, found 0)
<7> [307.198024] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1906, found 0)
<7> [307.198128] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [307.198231] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [307.198335] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [307.198438] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [307.198540] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [307.198653] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [307.198762] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [307.198869] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1800, found 0)
<7> [307.198974] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [307.199078] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [307.199180] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1906, found 0)
<7> [307.199283] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1906, found 0)
<7> [307.199386] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [307.199486] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [307.199586] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [307.199696] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [307.199800] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 347710, found 0)
<7> [307.199899] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 347710, found 0)
<7> [307.200001] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in port_clock (expected 432000, found 0)
<7> [307.200104] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:268:pipe B] fastset requirement not met, forcing full modeset
<7> [307.200212] xe 0000:00:02.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:268:pipe B] releasing DPLL 0
<7> [307.200444] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [307.200538] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:268:pipe B] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [307.200662] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:152:plane 1B] ddb ( 0 - 4051) -> ( 0 - 0), size 4051 -> 0
<7> [307.200749] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:262:cursor B] ddb (4051 - 4096) -> ( 0 - 0), size 45 -> 0
<7> [307.200825] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:152: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> [307.200900] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:152:plane 1B] lines 4, 13, 13, 13, 13, 13, 0, 0, 0, 4, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [307.200974] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:152:plane 1B] blocks 92, 297, 297, 297, 297, 297, 0, 0, 197, 92, 197 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [307.201047] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:152:plane 1B] min_ddb 183, 456, 456, 456, 456, 456, 0, 0, 198, 183, 198 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [307.201142] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:268:pipe B] data rate 0 num active planes 0
<7> [307.201263] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 0 qgv_peak_bw: 38400
<7> [307.201378] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 0 qgv_peak_bw: 38400
<7> [307.201491] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [307.201602] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [307.201724] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 0
<7> [307.201840] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:268:pipe B] min cdclk: 173855 kHz -> 0 kHz
<7> [307.201977] xe 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 21732 kHz -> 0 kHz
<7> [307.202090] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [307.202197] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 153600 kHz, actual 153600 kHz
<7> [307.202304] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [307.202442] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:268:pipe B] enable: no [modeset]
<7> [307.202558] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:152:plane 1B] fb: [NOFB], visible: no
<7> [307.202678] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:182:plane 2B] fb: [NOFB], visible: no
<7> [307.202797] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:212:plane 3B] fb: [NOFB], visible: no
<7> [307.202914] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:242:plane 4B] fb: [NOFB], visible: no
<7> [307.203026] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:252:plane 5B] fb: [NOFB], visible: no
<7> [307.203137] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:262:cursor B] fb: [NOFB], visible: no
<7> [307.203335] xe 0000:00:02.0: [drm:intel_psr_disable_locked [xe]] Disabling PSR2
<7> [307.223418] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [307.223903] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [307.428720] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:391:eDP-1] set backlight PWM = 0
<7> [307.429503] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe B
<7> [307.435057] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turn panel power off
<7> [307.435533] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [307.436019] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [307.488305] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [307.488921] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - short
<7> [307.496698] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [307.497163] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disable DPLL 0 (active 0x2, on? 1) for [CRTC:268:pipe B]
<7> [307.497325] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [307.498600] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disabling DPLL 0
<7> [307.499213] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [307.499878] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:390:DDI A/PHY A]
<7> [307.500319] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:399:DDI TC1/PHY TC1]
<7> [307.500758] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DP-MST A]
<7> [307.501157] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:402:DP-MST B]
<7> [307.501551] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST C]
<7> [307.501980] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:411:DDI TC2/PHY TC2]
<7> [307.502378] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DP-MST A]
<7> [307.502794] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:414:DP-MST B]
<7> [307.503186] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST C]
<7> [307.503578] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:419:DDI TC3/PHY TC3]
<7> [307.503980] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DP-MST A]
<7> [307.504364] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:422:DP-MST B]
<7> [307.504761] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST C]
<7> [307.505148] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:391:eDP-1]
<7> [307.505632] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [307.506032] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [307.506406] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [307.507026] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_B
<7> [307.507522] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [307.508007] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:268:pipe B]
<7> [307.508375] xe 0000:00:02.0: [drm:intel_set_cdclk [xe]] Post changing CDCLK to 153600 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [307.508855] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [307.509182] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=384
<7> [307.510716] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]
<7> [307.511257] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]
<7> [307.511342] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:391:eDP-1]
<7> [307.511583] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CONNECTOR:391:eDP-1] Limiting display bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [307.512035] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:390:DDI A/PHY A][CRTC:387:pipe C] DP link limits: pixel clock 347710 kHz DSC off max lanes 4 max rate 810000 max pipe_bpp 30 max link_bpp 30.0000
<7> [307.512352] xe 0000:00:02.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 4 clock 432000 bpp input 30 compressed 0.0000 link rate required 1303913 available 1728000
<7> [307.512664] xe 0000:00:02.0: [drm:intel_psr_compute_config [xe]] PSR2 not supported in transcoder C
<7> [307.512945] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:387:pipe C] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [307.513248] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:390:DDI A/PHY A] [CRTC:387:pipe C]
<7> [307.513552] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.enable (expected no, found yes)
<7> [307.513849] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.active (expected no, found yes)
<7> [307.514109] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2)
<7> [307.514351] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in lane_count (expected 0, found 4)
<7> [307.514579] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6329869/8388608 link 421991/524288)
<7> [307.514817] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [307.515033] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [307.515247] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [307.515459] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [307.515672] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)
<7> [307.515877] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)
<7> [307.516066] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)
<7> [307.516246] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)
<7> [307.516432] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [307.516604] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1800)
<7> [307.516782] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)
<7> [307.516954] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)
<7> [307.517118] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1906)
<7> [307.517266] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1906)
<7> [307.517408] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [307.517549] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [307.517698] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)
<7> [307.517844] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)
<7> [307.517984] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)
<7> [307.518133] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)
<7> [307.518277] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [307.518417] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1800)
<7> [307.518552] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)
<7> [307.518689] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)
<7> [307.518835] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1906)
<7> [307.518953] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1906)
<7> [307.519073] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [307.519192] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [307.519300] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [307.519407] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [307.519511] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 347710)
<7> [307.519610] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 347710)
<7> [307.519716] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in port_clock (expected 0, found 432000)
<7> [307.519822] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:387:pipe C] fastset requirement not met, forcing full modeset
<7> [307.520021] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xd (total dbuf slices 0xf), mbus joined? no->no
<7> [307.520131] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:387:pipe C] dbuf slices 0x0 -> 0xc, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x4
<7> [307.520255] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:271:plane 1C] ddb ( 0 - 0) -> ( 0 - 2003), size 0 -> 2003
<7> [307.520349] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:381:cursor C] ddb ( 0 - 0) -> (2003 - 2048), size 0 -> 45
<7> [307.520425] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [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> [307.520514] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:271:plane 1C] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 13, 13, 13, 13, 13, 0, 0, 0, 4, 0
<7> [307.520590] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:271:plane 1C] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 92, 297, 297, 297, 297, 297, 0, 0, 197, 92, 197
<7> [307.520698] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:271:plane 1C] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 183, 456, 456, 456, 456, 456, 0, 0, 198, 183, 198
<7> [307.520843] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:387:pipe C] data rate 1390840 num active planes 1
<7> [307.520975] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [307.521099] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [307.521219] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [307.521331] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [307.521438] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [307.521566] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:387:pipe C] min cdclk: 0 kHz -> 173855 kHz
<7> [307.521708] xe 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 0 kHz -> 21732 kHz
<7> [307.521822] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] Can change cdclk via squashing
<7> [307.521931] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [307.522043] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] New voltage level calculated to be logical 0, actual 0
<7> [307.522176] xe 0000:00:02.0: [drm:intel_find_dpll [xe]] [CRTC:387:pipe C] allocated DPLL 0
<7> [307.522284] xe 0000:00:02.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:387:pipe C] reserving DPLL 0
<7> [307.522390] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:387:pipe C] enable: yes [modeset]
<7> [307.522514] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [307.522623] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: C, pipe bpp: 30, dithering: 0
<7> [307.522825] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [307.522939] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [307.523044] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [307.523148] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [307.523253] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [307.523256] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m_n: lanes: 4; data_m: 6329869, data_n: 8388608, link_m: 421991, link_n: 524288, tu: 64
<7> [307.523364] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - long
<7> [307.523366] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m2_n2: lanes: 4; data_m: 0, data_n: 0, link_m: 0, link_n: 0, tu: 0
<7> [307.523466] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [307.523474] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [307.523581] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [307.523598] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [307.523696] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: disabled, panel replay: disabled, selective fetch: disabled
<7> [307.523812] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0
<7> [307.523923] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [307.524032] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 2, length 8
<7> [307.524141] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [307.524249] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [307.524358] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [307.524462] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [307.524566] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [307.524677] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [307.524784] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [307.524893] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: no, fixed rr: yes, vmin: 1906, vmax: 1906, flipline: 1906, pipeline full: 0, guardband: 104 vsync start: 103, vsync end: 97
<7> [307.525006] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1802, vmax vblank: 1802, vmin vtotal: 1906, vmax vtotal: 1906
<7> [307.525114] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] requested mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [307.525225] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [307.525335] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=347710, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1906 vs=1803-1809 vt=1906, flags=0xa
<7> [307.525446] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x40 0xa
<7> [307.525556] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=347710, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1906 vs=1803-1809 vt=1906, flags=0xa
<7> [307.525672] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 432000, pipe src: 2880x1800+0+0, pixel rate 347710, min cdclk 173855
<7> [307.525786] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [307.525890] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [307.525994] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [307.526099] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [307.526203] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: yes, tbt_mode: no
<7> [307.526309] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_hw_state: clock: 432000, fracen: no,
<7> [307.526420] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] multiplier: 225, tx_clk_div: 1.
<7> [307.526528] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_rawhw_state:
<7> [307.526642] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] tx: 0x10, cmn: 0x21
<7> [307.526757] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1
<7> [307.526868] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [307.526977] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [307.527085] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [307.527193] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23
<7> [307.527298] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [307.527402] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [307.527506] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [307.527610] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [307.527726] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [307.527836] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [307.527947] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [307.528059] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [307.528166] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [307.528275] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [307.528385] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [307.528490] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [307.528598] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [307.528724] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:271:plane 1C] fb: [FB:433] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [307.528736] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [307.528839] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [307.528898] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [307.528944] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [307.529027] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [307.529050] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:301:plane 2C] fb: [NOFB], visible: no
<7> [307.529156] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:331:plane 3C] fb: [NOFB], visible: no
<7> [307.529166] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling always-on
<7> [307.529268] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:361:plane 4C] fb: [NOFB], visible: no
<7> [307.529380] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:371:plane 5C] fb: [NOFB], visible: no
<7> [307.529493] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:381:cursor C] fb: [NOFB], visible: no
<7> [307.530018] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling always-on
<7> [307.530139] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [307.530251] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [307.530416] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [307.530562] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_C
<7> [307.530734] xe 0000:00:02.0: [drm:intel_set_cdclk [xe]] Pre changing CDCLK to 192000 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [307.530861] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [307.530967] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=480
<7> [307.531080] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:390:DDI A/PHY A]
<7> [307.531167] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:399:DDI TC1/PHY TC1]
<7> [307.531249] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DP-MST A]
<7> [307.531330] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:402:DP-MST B]
<7> [307.531410] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST C]
<7> [307.531489] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:411:DDI TC2/PHY TC2]
<7> [307.531568] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DP-MST A]
<7> [307.531655] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:414:DP-MST B]
<7> [307.531736] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST C]
<7> [307.531814] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:419:DDI TC3/PHY TC3]
<7> [307.531893] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DP-MST A]
<7> [307.531971] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:422:DP-MST B]
<7> [307.532050] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST C]
<7> [307.532155] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xd
<7> [307.532342] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no)
<7> [307.532483] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [307.533687] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enable DPLL 0 (active 0x4, on? 0) for [CRTC:387:pipe C]
<7> [307.533801] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enabling DPLL 0
<7> [307.535521] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turn panel power on
<7> [307.535630] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 wait for panel power cycle (462 ms remaining)
<7> [308.005117] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [308.036591] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [308.037346] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 wait for panel power on
<7> [308.037977] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [308.074296] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [308.074946] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - long
<7> [308.075449] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [308.075949] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [308.251024] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [308.251851] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turning VDD on
<7> [308.252432] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [308.255073] xe 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX A/DDI A/PHY A: DPCD: 14 1e 44 c1 00 00 01 c0 02 00 02 00 00 0b 80
<7> [308.256460] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [308.257545] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 05
<7> [308.259061] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [308.260474] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [308.261890] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Clock recovery OK
<7> [308.262437] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Using DP training pattern TPS4
<7> [308.264249] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [308.264807] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Link Training passed at link rate = 432000, lane count = 4
<7> [308.265846] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe C
<7> [308.266570] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [308.267072] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe C
<7> [308.267677] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:391:eDP-1] set backlight PWM = 4518
<7> [308.269620] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] reserved 14745600 bytes of contiguous stolen space for FBC, limit: 2
<7> [308.270035] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] Enabling FBC on [PLANE:271:plane 1C]
<7> [308.283407] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR1
<7> [308.285342] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:391:eDP-1]
<7> [308.285991] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:387:pipe C]
<7> [310.796730] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turning VDD off
<7> [310.797266] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x00000067
<7> [318.315148] xe 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:434] from all active usage due to RMFB ioctl
<7> [318.315473] xe 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:433] from all active usage due to RMFB ioctl
<7> [318.315586] xe 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:271:plane 1C] because [FB:433] is removed
<7> [318.315783] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:271:plane 1C] ddb ( 0 - 2003) -> ( 0 - 0), size 2003 -> 0
<7> [318.316358] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [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> [318.316830] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:271:plane 1C] lines 4, 13, 13, 13, 13, 13, 0, 0, 0, 4, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [318.317218] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:271:plane 1C] blocks 92, 297, 297, 297, 297, 297, 0, 0, 197, 92, 197 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [318.317618] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:271:plane 1C] min_ddb 183, 456, 456, 456, 456, 456, 0, 0, 198, 183, 198 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [318.318123] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:387:pipe C] data rate 0 num active planes 0
<7> [318.318753] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 0 qgv_peak_bw: 38400
<7> [318.319328] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 0 qgv_peak_bw: 38400
<7> [318.319910] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [318.320440] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 38400
<7> [318.320959] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 0
<7> [318.322388] xe 0000:00:02.0: [drm:intel_psr_disable_locked [xe]] Disabling PSR1
<7> [318.323047] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turning VDD on
<7> [318.323477] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006f
<7> [318.331755] xe 0000:00:02.0: [drm:__intel_fbc_disable [xe]] Disabling FBC on [PLANE:271:plane 1C]
<6> [318.349995] [IGT] kms_flip: finished subtest C-eDP1, SUCCESS
<6> [318.353782] [IGT] kms_flip: finished subtest flip-vs-expired-vblank-interruptible, FAIL
<7> [318.371328] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CONNECTOR:391:eDP-1] Limiting display bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [318.371787] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:390:DDI A/PHY A][CRTC:149:pipe A] DP link limits: pixel clock 347710 kHz DSC off max lanes 4 max rate 810000 max pipe_bpp 30 max link_bpp 30.0000
<7> [318.372139] xe 0000:00:02.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 4 clock 432000 bpp input 30 compressed 0.0000 link rate required 1303913 available 1728000
<7> [318.372483] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:149:pipe A] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [318.373030] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:390:DDI A/PHY A] [CRTC:149:pipe A]
<7> [318.373367] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [318.373655] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [318.373959] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [318.374227] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [318.374490] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6329869/8388608 link 421991/524288)
<7> [318.374931] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [318.375199] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [318.375414] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)
<7> [318.375628] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)
<7> [318.375860] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)
<7> [318.376059] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)
<7> [318.376254] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)
<7> [318.376445] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)
<7> [318.376624] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)
<7> [318.376811] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1800)
<7> [318.376975] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)
<7> [318.377160] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)
<7> [318.377336] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1906)
<7> [318.377487] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1906)
<7> [318.377632] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)
<7> [318.377785] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)
<7> [318.377922] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)
<7> [318.378054] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)
<7> [318.378185] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)
<7> [318.378310] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)
<7> [318.378436] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)
<7> [318.378558] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1800)
<7> [318.378676] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)
<7> [318.378829] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)
<7> [318.378943] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1906)
<7> [318.379067] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1906)
<7> [318.379191] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [318.379305] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [318.379414] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [318.379525] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 30)
<7> [318.379629] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 347710)
<7> [318.379775] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 347710)
<7> [318.379877] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:149:pipe A] fastset requirement not met in port_clock (expected 0, found 432000)
<7> [318.379979] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:149:pipe A] fastset requirement not met, forcing full modeset
<7> [318.380077] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.enable (expected yes, found no)
<7> [318.380169] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.active (expected yes, found no)
<7> [318.380259] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in cpu_transcoder (expected 2, found -1)
<7> [318.380348] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in lane_count (expected 4, found 0)
<7> [318.380437] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in dp_m_n (expected tu 64 data 6329869/8388608 link 421991/524288, found tu 0, data 0/0 link 0/0)
<7> [318.380525] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [318.380612] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [318.380732] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [318.380829] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [318.380925] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [318.381015] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [318.381091] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [318.381166] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [318.381239] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [318.381315] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1800, found 0)
<7> [318.381396] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)
<7> [318.381480] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)
<7> [318.381563] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1906, found 0)
<7> [318.381647] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1906, found 0)
<7> [318.381749] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)
<7> [318.381849] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)
<7> [318.381948] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)
<7> [318.382037] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)
<7> [318.382115] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)
<7> [318.382189] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)
<7> [318.382270] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)
<7> [318.382351] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1800, found 0)
<7> [318.382431] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)
<7> [318.382512] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)
<7> [318.382603] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1906, found 0)
<7> [318.382714] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1906, found 0)
<7> [318.382809] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [318.382903] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [318.382995] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [318.383069] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in pipe_bpp (expected 30, found 0)
<7> [318.383139] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 347710, found 0)
<7> [318.383215] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 347710, found 0)
<7> [318.383293] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe C] fastset requirement not met in port_clock (expected 432000, found 0)
<7> [318.383373] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:387:pipe C] fastset requirement not met, forcing full modeset
<7> [318.383458] xe 0000:00:02.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:387:pipe C] releasing DPLL 0
<7> [318.383637] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xd -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [318.383809] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:149:pipe A] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x4 -> 0x1
<7> [318.383905] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:387:pipe C] dbuf slices 0xc -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x4 -> 0x1
<7> [318.383991] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:33:plane 1A] ddb ( 0 - 0) -> ( 0 - 4051), size 0 -> 4051
<7> [318.384066] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:143:cursor A] ddb ( 0 - 0) -> (4051 - 4096), size 0 -> 45
<7> [318.384138] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33: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> [318.384208] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 13, 13, 13, 13, 13, 0, 0, 0, 3, 0
<7> [318.384276] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 24, 313, 313, 313, 313, 313, 0, 0, 38, 73, 87
<7> [318.384342] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:33:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 28, 346, 346, 346, 346, 346, 0, 0, 39, 82, 88
<7> [318.384407] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:381:cursor C] ddb (2003 - 2048) -> ( 0 - 0), size 45 -> 0
<7> [318.384493] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:149:pipe A] data rate 1390840 num active planes 1
<7> [318.384584] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400
<7> [318.384669] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400
<7> [318.384771] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [318.384881] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400
<7> [318.384994] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461
<7> [318.385086] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:149:pipe A] min cdclk: 0 kHz -> 173855 kHz
<7> [318.385170] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:387:pipe C] min cdclk: 173855 kHz -> 0 kHz
<7> [318.385294] xe 0000:00:02.0: [drm:intel_find_dpll [xe]] [CRTC:149:pipe A] allocated DPLL 0
<7> [318.385377] xe 0000:00:02.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:149:pipe A] reserving DPLL 0
<7> [318.385467] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:149:pipe A] enable: yes [modeset]
<7> [318.385552] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [318.385636] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: A, pipe bpp: 30, dithering: 0
<7> [318.385729] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [318.385826] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [318.385940] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [318.386054] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [318.386164] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m_n: lanes: 4; data_m: 6329869, data_n: 8388608, link_m: 421991, link_n: 524288, tu: 64
<7> [318.386257] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m2_n2: lanes: 4; data_m: 0, data_n: 0, link_m: 0, link_n: 0, tu: 0
<7> [318.386353] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [318.386460] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [318.386562] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled
<7> [318.386664] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0
<7> [318.386774] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [318.386876] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 4, length 14
<7> [318.386978] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [318.387084] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [318.387174] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [318.387267] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [318.387351] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [318.387438] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [318.387519] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [318.387602] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: no, fixed rr: yes, vmin: 1906, vmax: 1906, flipline: 1906, pipeline full: 0, guardband: 104 vsync start: 103, vsync end: 97
<7> [318.387683] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1802, vmax vblank: 1802, vmin vtotal: 1906, vmax vtotal: 1906
<7> [318.387783] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] requested mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [318.387881] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [318.387980] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=347710, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1906 vs=1803-1809 vt=1906, flags=0xa
<7> [318.388083] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x40 0xa
<7> [318.388172] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=347710, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1906 vs=1803-1809 vt=1906, flags=0xa
<7> [318.388254] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 432000, pipe src: 2880x1800+0+0, pixel rate 347710, min cdclk 173855
<7> [318.388344] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0
<7> [318.388440] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [318.388533] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [318.388627] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [318.388707] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: yes, tbt_mode: no
<7> [318.388802] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_hw_state: clock: 432000, fracen: no,
<7> [318.388901] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] multiplier: 225, tx_clk_div: 1.
<7> [318.389001] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] c10pll_rawhw_state:
<7> [318.389093] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] tx: 0x10, cmn: 0x21
<7> [318.389176] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[0] = 0x4, pll[1] = 0x0, pll[2] = 0xa2, pll[3] = 0x1
<7> [318.389259] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0
<7> [318.389340] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0
<7> [318.389421] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[12] = 0x0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x1
<7> [318.389502] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pll[16] = 0x85, pll[17] = 0xf, pll[18] = 0xe6, pll[19] = 0x23
<7> [318.389580] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [318.389652] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [318.389741] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [318.389837] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [318.389934] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [318.390035] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [318.390121] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [318.390201] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [318.390287] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [318.390382] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [318.390475] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [318.390568] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [318.390644] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [318.390724] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:33:plane 1A] fb: [FB:392] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [318.390820] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [318.390913] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0
<7> [318.391012] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:63:plane 2A] fb: [NOFB], visible: no
<7> [318.391102] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:93:plane 3A] fb: [NOFB], visible: no
<7> [318.391180] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:123:plane 4A] fb: [NOFB], visible: no
<7> [318.391263] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:133:plane 5A] fb: [NOFB], visible: no
<7> [318.391345] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:143:cursor A] fb: [NOFB], visible: no
<7> [318.391428] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:387:pipe C] enable: no [modeset]
<7> [318.391511] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:271:plane 1C] fb: [NOFB], visible: no
<7> [318.391592] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:301:plane 2C] fb: [NOFB], visible: no
<7> [318.391667] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:331:plane 3C] fb: [NOFB], visible: no
<7> [318.391756] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:361:plane 4C] fb: [NOFB], visible: no
<7> [318.391853] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:371:plane 5C] fb: [NOFB], visible: no
<7> [318.391951] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:381:cursor C] fb: [NOFB], visible: no
<7> [318.392138] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_A
<7> [318.393186] xe 0000:00:02.0: [drm:intel_alpm_disable [xe]] Disabling ALPM
<7> [318.393322] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]]
<7> [318.596906] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:391:eDP-1] set backlight PWM = 0
<7> [318.597551] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe C
<7> [318.598737] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turn panel power off
<7> [318.599032] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 wait for panel power off time
<7> [318.599333] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060
<7> [318.599170] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [318.600235] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - short
<7> [318.651873] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000
<7> [318.652505] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - short
<7> [318.659805] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [318.660366] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disable DPLL 0 (active 0x4, on? 1) for [CRTC:387:pipe C]
<7> [318.660409] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [318.661009] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [318.661831] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disabling DPLL 0
<7> [318.662350] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A
<7> [318.662885] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:390:DDI A/PHY A]
<7> [318.663250] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:399:DDI TC1/PHY TC1]
<7> [318.663602] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:401:DP-MST A]
<7> [318.664037] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:402:DP-MST B]
<7> [318.664401] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:403:DP-MST C]
<7> [318.664740] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:411:DDI TC2/PHY TC2]
<7> [318.665056] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:413:DP-MST A]
<7> [318.665372] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:414:DP-MST B]
<7> [318.665683] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:415:DP-MST C]
<7> [318.666033] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:419:DDI TC3/PHY TC3]
<7> [318.666359] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:421:DP-MST A]
<7> [318.666657] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:422:DP-MST B]
<7> [318.666998] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:423:DP-MST C]
<7> [318.667741] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [318.668244] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [318.668559] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 8 (mbus joined: yes)
<7> [318.668960] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A
<7> [318.670698] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:149:pipe A]
<7> [318.671183] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enabling DPLL 0
<7> [318.673329] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turn panel power on
<7> [318.673663] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 wait for panel power cycle (487 ms remaining)
<7> [318.686759] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [318.687192] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - long
<7> [318.687557] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [318.688045] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [319.165085] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060
<7> [319.252895] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [319.253357] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 wait for panel power on
<7> [319.253800] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063
<7> [319.290177] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [319.290729] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:390:DDI A/PHY A] - long
<7> [319.291169] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20
<7> [319.291818] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:390:DDI A/PHY A]
<7> [319.464614] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete
<7> [319.465030] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 turning VDD on
<7> [319.465318] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:390:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b
<7> [319.467344] xe 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX A/DDI A/PHY A: DPCD: 14 1e 44 c1 00 00 01 c0 02 00 02 00 00 0b 80
<7> [319.468641] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Reloading eDP link rates
<7> [319.469494] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 05
<7> [319.470749] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [319.472019] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Using DP training pattern TPS1
<7> [319.473225] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Clock recovery OK
<7> [319.473693] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Using DP training pattern TPS4
<7> [319.475306] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful
<7> [319.475733] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:391:eDP-1][ENCODER:390:DDI A/PHY A][DPRX] Link Training passed at link rate = 432000, lane count = 4
<7> [319.476606] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe A
<7> [319.477196] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]]
<7> [319.477635] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe A
<7> [319.478148] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:391:eDP-1] set backlight PWM = 4518
<7> [319.494087] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR2
<7> [319.495882] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:391:eDP-1]
<7> [319.496290] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:149:pipe A]
<7> [319.498876] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_C
<7> [319.499814] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [319.500209] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:387:pipe C]
<7> [319.501158] xe 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0
<6> [319.502393] [IGT] kms_flip: exiting, ret=98
<6> [319.511146] Console: switching to colour frame buffer device 180x56
<7> [319.545842] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [319.546192] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [319.546489] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
|