Machine description: shard-tglu-9
Result:
integration-manifest git-log-oneline i915_display_info2 igt_runner2 run2 runtimes2 results2.json i915_display_info_post_exec2 boot2 dmesg2
| Detail | Value |
|---|---|
| Duration | 9.16 seconds |
| Hostname |
shard-tglu-9 |
| Igt-Version |
IGT-Version: 1.29-NO-GIT (x86_64) (Linux: 6.11.0-CI_DRM_15453-g1522f1315052+ x86_64) |
| Out |
Starting dynamic subtest: D-HDMI-A1 1920x1080: 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5 Expected frametime: 8335us; measured 8334.4us +- 3.140us accuracy 0.11% Stack trace: #0 ../lib/igt_core.c:2051 __igt_fail_assert() #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0() #2 ../tests/kms_flip.c:1845 run_test() #3 ../tests/kms_flip.c:2078 __igt_unique____real_main2001() #4 ../tests/kms_flip.c:2001 main() #5 [__libc_init_first+0x8a] #6 [__libc_start_main+0x8b] #7 [_start+0x25] Dynamic subtest D-HDMI-A1: FAIL (9.157s) Subtest plain-flip-ts-check: FAIL (32.557s) Stack trace: #0 ../lib/igt_core.c:2051 __igt_fail_assert() #1 ../lib/igt_kms.c:2209 kmstest_set_connector_dpms() #2 ../lib/igt_core.c:2930 igt_atexit_handler() #3 [erand48_r+0x1d6] #4 [exit+0x1e] #5 ../lib/igt_core.c:2386 igt_exit() #6 [main+0x3f] #7 [__libc_init_first+0x8a] #8 [__libc_start_main+0x8b] #9 [_start+0x25] |
| Err |
Starting dynamic subtest: D-HDMI-A1 (kms_flip:1367) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674: (kms_flip:1367) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon) Dynamic subtest D-HDMI-A1 failed. **** DEBUG **** (kms_flip:1367) DEBUG: flip ts/seq: last 100.078705/574, current 100.087036/575: elapsed=8332.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.087036 last_received_ts = 100.086967 last_seq = 575 current_ts = 100.095375 current_received_ts = 100.095230 current_seq = 576 count = 554 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.087036/575, current 100.095375/576: elapsed=8337.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.095375 last_received_ts = 100.095230 last_seq = 576 current_ts = 100.103706 current_received_ts = 100.103516 current_seq = 577 count = 555 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.095375/576, current 100.103706/577: elapsed=8335.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.103706 last_received_ts = 100.103516 last_seq = 577 current_ts = 100.112038 current_received_ts = 100.111855 current_seq = 578 count = 556 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.103706/577, current 100.112038/578: elapsed=8328.0us expected=8334.4us +- 41.7us, error 0.1% (kms_flip:1367) DEBUG: name = flip last_ts = 100.112038 last_received_ts = 100.111855 last_seq = 578 current_ts = 100.120377 current_received_ts = 100.120193 current_seq = 579 count = 557 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.112038/578, current 100.120377/579: elapsed=8340.0us expected=8334.4us +- 41.7us, error 0.1% (kms_flip:1367) DEBUG: name = flip last_ts = 100.120377 last_received_ts = 100.120193 last_seq = 579 current_ts = 100.128708 current_received_ts = 100.128525 current_seq = 580 count = 558 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.120377/579, current 100.128708/580: elapsed=8330.0us expected=8334.4us +- 41.7us, error 0.1% (kms_flip:1367) DEBUG: name = flip last_ts = 100.128708 last_received_ts = 100.128525 last_seq = 580 current_ts = 100.137047 current_received_ts = 100.136978 current_seq = 581 count = 559 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.128708/580, current 100.137047/581: elapsed=8337.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.137047 last_received_ts = 100.136978 last_seq = 581 current_ts = 100.145378 current_received_ts = 100.145187 current_seq = 582 count = 560 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.137047/581, current 100.145378/582: elapsed=8335.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.145378 last_received_ts = 100.145187 last_seq = 582 current_ts = 100.153709 current_received_ts = 100.153481 current_seq = 583 count = 561 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.145378/582, current 100.153709/583: elapsed=8331.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.153709 last_received_ts = 100.153481 last_seq = 583 current_ts = 100.162048 current_received_ts = 100.161980 current_seq = 584 count = 562 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.153709/583, current 100.162048/584: elapsed=8338.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.162048 last_received_ts = 100.161980 last_seq = 584 current_ts = 100.170387 current_received_ts = 100.170242 current_seq = 585 count = 563 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.162048/584, current 100.170387/585: elapsed=8337.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.170387 last_received_ts = 100.170242 last_seq = 585 current_ts = 100.178719 current_received_ts = 100.178535 current_seq = 586 count = 564 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.170387/585, current 100.178719/586: elapsed=8331.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.178719 last_received_ts = 100.178535 last_seq = 586 current_ts = 100.187050 current_received_ts = 100.186989 current_seq = 587 count = 565 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.178719/586, current 100.187050/587: elapsed=8336.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.187050 last_received_ts = 100.186989 last_seq = 587 current_ts = 100.195381 current_received_ts = 100.195328 current_seq = 588 count = 566 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.187050/587, current 100.195381/588: elapsed=8330.0us expected=8334.4us +- 41.7us, error 0.1% (kms_flip:1367) DEBUG: name = flip last_ts = 100.195381 last_received_ts = 100.195328 last_seq = 588 current_ts = 100.203720 current_received_ts = 100.203529 current_seq = 589 count = 567 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.195381/588, current 100.203720/589: elapsed=8336.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.203720 last_received_ts = 100.203529 last_seq = 589 current_ts = 100.212059 current_received_ts = 100.211868 current_seq = 590 count = 568 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.203720/589, current 100.212059/590: elapsed=8340.0us expected=8334.4us +- 41.7us, error 0.1% (kms_flip:1367) DEBUG: name = flip last_ts = 100.212059 last_received_ts = 100.211868 last_seq = 590 current_ts = 100.220390 current_received_ts = 100.220322 current_seq = 591 count = 569 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.212059/590, current 100.220390/591: elapsed=8331.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.220390 last_received_ts = 100.220322 last_seq = 591 current_ts = 100.228722 current_received_ts = 100.228661 current_seq = 592 count = 570 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.220390/591, current 100.228722/592: elapsed=8333.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.228722 last_received_ts = 100.228661 last_seq = 592 current_ts = 100.237061 current_received_ts = 100.236877 current_seq = 593 count = 571 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.228722/592, current 100.237061/593: elapsed=8340.0us expected=8334.4us +- 41.7us, error 0.1% (kms_flip:1367) DEBUG: name = flip last_ts = 100.237061 last_received_ts = 100.236877 last_seq = 593 current_ts = 100.245392 current_received_ts = 100.245300 current_seq = 594 count = 572 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.237061/593, current 100.245392/594: elapsed=8331.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.245392 last_received_ts = 100.245300 last_seq = 594 current_ts = 100.253731 current_received_ts = 100.253616 current_seq = 595 count = 573 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.245392/594, current 100.253731/595: elapsed=8335.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.253731 last_received_ts = 100.253616 last_seq = 595 current_ts = 100.262062 current_received_ts = 100.261833 current_seq = 596 count = 574 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.253731/595, current 100.262062/596: elapsed=8332.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.262062 last_received_ts = 100.261833 last_seq = 596 current_ts = 100.270393 current_received_ts = 100.270241 current_seq = 597 count = 575 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.262062/596, current 100.270393/597: elapsed=8336.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.270393 last_received_ts = 100.270241 last_seq = 597 current_ts = 100.278732 current_received_ts = 100.278664 current_seq = 598 count = 576 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.270393/597, current 100.278732/598: elapsed=8338.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.278732 last_received_ts = 100.278664 last_seq = 598 current_ts = 100.287071 current_received_ts = 100.287033 current_seq = 599 count = 577 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.278732/598, current 100.287071/599: elapsed=8335.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.287071 last_received_ts = 100.287033 last_seq = 599 current_ts = 100.295403 current_received_ts = 100.295250 current_seq = 600 count = 578 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.287071/599, current 100.295403/600: elapsed=8332.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.295403 last_received_ts = 100.295250 last_seq = 600 current_ts = 100.303734 current_received_ts = 100.303543 current_seq = 601 count = 579 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.295403/600, current 100.303734/601: elapsed=8334.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.303734 last_received_ts = 100.303543 last_seq = 601 current_ts = 100.312073 current_received_ts = 100.311928 current_seq = 602 count = 580 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.303734/601, current 100.312073/602: elapsed=8333.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.312073 last_received_ts = 100.311928 last_seq = 602 current_ts = 100.320404 current_received_ts = 100.320335 current_seq = 603 count = 581 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.312073/602, current 100.320404/603: elapsed=8336.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.320404 last_received_ts = 100.320335 last_seq = 603 current_ts = 100.328743 current_received_ts = 100.328682 current_seq = 604 count = 582 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.320404/603, current 100.328743/604: elapsed=8335.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.328743 last_received_ts = 100.328682 last_seq = 604 current_ts = 100.337074 current_received_ts = 100.336929 current_seq = 605 count = 583 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.328743/604, current 100.337074/605: elapsed=8332.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.337074 last_received_ts = 100.336929 last_seq = 605 current_ts = 100.345406 current_received_ts = 100.345222 current_seq = 606 count = 584 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.337074/605, current 100.345406/606: elapsed=8334.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.345406 last_received_ts = 100.345222 last_seq = 606 current_ts = 100.353745 current_received_ts = 100.353477 current_seq = 607 count = 585 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.345406/606, current 100.353745/607: elapsed=8338.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.353745 last_received_ts = 100.353477 last_seq = 607 current_ts = 100.362076 current_received_ts = 100.362030 current_seq = 608 count = 586 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.353745/607, current 100.362076/608: elapsed=8335.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.362076 last_received_ts = 100.362030 last_seq = 608 current_ts = 100.370415 current_received_ts = 100.370232 current_seq = 609 count = 587 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.362076/608, current 100.370415/609: elapsed=8335.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.370415 last_received_ts = 100.370232 last_seq = 609 current_ts = 100.378746 current_received_ts = 100.378571 current_seq = 610 count = 588 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.370415/609, current 100.378746/610: elapsed=8331.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.378746 last_received_ts = 100.378571 last_seq = 610 current_ts = 100.387085 current_received_ts = 100.386993 current_seq = 611 count = 589 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.378746/610, current 100.387085/611: elapsed=8338.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.387085 last_received_ts = 100.386993 last_seq = 611 current_ts = 100.395416 current_received_ts = 100.395233 current_seq = 612 count = 590 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.387085/611, current 100.395416/612: elapsed=8333.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.395416 last_received_ts = 100.395233 last_seq = 612 current_ts = 100.403755 current_received_ts = 100.403511 current_seq = 613 count = 591 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.395416/612, current 100.403755/613: elapsed=8337.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.403755 last_received_ts = 100.403511 last_seq = 613 current_ts = 100.412086 current_received_ts = 100.412056 current_seq = 614 count = 592 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.403755/613, current 100.412086/614: elapsed=8332.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.412086 last_received_ts = 100.412056 last_seq = 614 current_ts = 100.420418 current_received_ts = 100.420357 current_seq = 615 count = 593 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.412086/614, current 100.420418/615: elapsed=8332.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.420418 last_received_ts = 100.420357 last_seq = 615 current_ts = 100.428757 current_received_ts = 100.428680 current_seq = 616 count = 594 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.420418/615, current 100.428757/616: elapsed=8341.0us expected=8334.4us +- 41.7us, error 0.1% (kms_flip:1367) DEBUG: name = flip last_ts = 100.428757 last_received_ts = 100.428680 last_seq = 616 current_ts = 100.437088 current_received_ts = 100.437050 current_seq = 617 count = 595 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.428757/616, current 100.437088/617: elapsed=8333.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.437088 last_received_ts = 100.437050 last_seq = 617 current_ts = 100.445419 current_received_ts = 100.445358 current_seq = 618 count = 596 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.437088/617, current 100.445419/618: elapsed=8332.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.445419 last_received_ts = 100.445358 last_seq = 618 current_ts = 100.453758 current_received_ts = 100.453491 current_seq = 619 count = 597 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.445419/618, current 100.453758/619: elapsed=8333.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.453758 last_received_ts = 100.453491 last_seq = 619 current_ts = 100.462097 current_received_ts = 100.461914 current_seq = 620 count = 598 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.453758/619, current 100.462097/620: elapsed=8338.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.462097 last_received_ts = 100.461914 last_seq = 620 current_ts = 100.470428 current_received_ts = 100.470253 current_seq = 621 count = 599 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.462097/620, current 100.470428/621: elapsed=8331.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.470428 last_received_ts = 100.470253 last_seq = 621 current_ts = 100.478760 current_received_ts = 100.478714 current_seq = 622 count = 600 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.470428/621, current 100.478760/622: elapsed=8337.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.478760 last_received_ts = 100.478714 last_seq = 622 current_ts = 100.487099 current_received_ts = 100.486954 current_seq = 623 count = 601 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.478760/622, current 100.487099/623: elapsed=8334.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.487099 last_received_ts = 100.486954 last_seq = 623 current_ts = 100.495438 current_received_ts = 100.495247 current_seq = 624 count = 602 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.487099/623, current 100.495438/624: elapsed=8338.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.495438 last_received_ts = 100.495247 last_seq = 624 current_ts = 100.503769 current_received_ts = 100.503639 current_seq = 625 count = 603 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.495438/624, current 100.503769/625: elapsed=8332.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.503769 last_received_ts = 100.503639 last_seq = 625 current_ts = 100.512100 current_received_ts = 100.512070 current_seq = 626 count = 604 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.503769/625, current 100.512100/626: elapsed=8334.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.512100 last_received_ts = 100.512070 last_seq = 626 current_ts = 100.520432 current_received_ts = 100.520302 current_seq = 627 count = 605 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.512100/626, current 100.520432/627: elapsed=8332.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.520432 last_received_ts = 100.520302 last_seq = 627 current_ts = 100.528770 current_received_ts = 100.528587 current_seq = 628 count = 606 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.520432/627, current 100.528770/628: elapsed=8341.0us expected=8334.4us +- 41.7us, error 0.1% (kms_flip:1367) DEBUG: name = flip last_ts = 100.528770 last_received_ts = 100.528587 last_seq = 628 current_ts = 100.537109 current_received_ts = 100.537033 current_seq = 629 count = 607 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.528770/628, current 100.537109/629: elapsed=8335.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.537109 last_received_ts = 100.537033 last_seq = 629 current_ts = 100.545441 current_received_ts = 100.545372 current_seq = 630 count = 608 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.537109/629, current 100.545441/630: elapsed=8334.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.545441 last_received_ts = 100.545372 last_seq = 630 current_ts = 100.553772 current_received_ts = 100.553520 current_seq = 631 count = 609 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.545441/630, current 100.553772/631: elapsed=8332.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.553772 last_received_ts = 100.553520 last_seq = 631 current_ts = 100.562111 current_received_ts = 100.561928 current_seq = 632 count = 610 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.553772/631, current 100.562111/632: elapsed=8336.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.562111 last_received_ts = 100.561928 last_seq = 632 current_ts = 100.570442 current_received_ts = 100.570374 current_seq = 633 count = 611 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.562111/632, current 100.570442/633: elapsed=8334.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.570442 last_received_ts = 100.570374 last_seq = 633 current_ts = 100.578781 current_received_ts = 100.578629 current_seq = 634 count = 612 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.570442/633, current 100.578781/634: elapsed=8334.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.578781 last_received_ts = 100.578629 last_seq = 634 current_ts = 100.587112 current_received_ts = 100.586922 current_seq = 635 count = 613 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.578781/634, current 100.587112/635: elapsed=8331.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.587112 last_received_ts = 100.586922 last_seq = 635 current_ts = 100.595444 current_received_ts = 100.595383 current_seq = 636 count = 614 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.587112/635, current 100.595444/636: elapsed=8337.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.595444 last_received_ts = 100.595383 last_seq = 636 current_ts = 100.603783 current_received_ts = 100.603714 current_seq = 637 count = 615 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.595444/636, current 100.603783/637: elapsed=8336.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.603783 last_received_ts = 100.603714 last_seq = 637 current_ts = 100.612114 current_received_ts = 100.612076 current_seq = 638 count = 616 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.603783/637, current 100.612114/638: elapsed=8333.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.612114 last_received_ts = 100.612076 last_seq = 638 current_ts = 100.620453 current_received_ts = 100.620300 current_seq = 639 count = 617 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.612114/638, current 100.620453/639: elapsed=8335.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.620453 last_received_ts = 100.620300 last_seq = 639 current_ts = 100.628784 current_received_ts = 100.628593 current_seq = 640 count = 618 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.620453/639, current 100.628784/640: elapsed=8337.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.628784 last_received_ts = 100.628593 last_seq = 640 current_ts = 100.637115 current_received_ts = 100.637070 current_seq = 641 count = 619 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.628784/640, current 100.637115/641: elapsed=8331.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.637115 last_received_ts = 100.637070 last_seq = 641 current_ts = 100.645454 current_received_ts = 100.645271 current_seq = 642 count = 620 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.637115/641, current 100.645454/642: elapsed=8336.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.645454 last_received_ts = 100.645271 last_seq = 642 current_ts = 100.653786 current_received_ts = 100.653603 current_seq = 643 count = 621 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.645454/642, current 100.653786/643: elapsed=8331.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.653786 last_received_ts = 100.653603 last_seq = 643 current_ts = 100.662125 current_received_ts = 100.661942 current_seq = 644 count = 622 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.653786/643, current 100.662125/644: elapsed=8339.0us expected=8334.4us +- 41.7us, error 0.1% (kms_flip:1367) DEBUG: name = flip last_ts = 100.662125 last_received_ts = 100.661942 last_seq = 644 current_ts = 100.670456 current_received_ts = 100.670395 current_seq = 645 count = 623 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.662125/644, current 100.670456/645: elapsed=8334.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.670456 last_received_ts = 100.670395 last_seq = 645 current_ts = 100.678795 current_received_ts = 100.678642 current_seq = 646 count = 624 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.670456/645, current 100.678795/646: elapsed=8335.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.678795 last_received_ts = 100.678642 last_seq = 646 current_ts = 100.687126 current_received_ts = 100.687073 current_seq = 647 count = 625 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.678795/646, current 100.687126/647: elapsed=8332.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.687126 last_received_ts = 100.687073 last_seq = 647 current_ts = 100.695465 current_received_ts = 100.695389 current_seq = 648 count = 626 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.687126/647, current 100.695465/648: elapsed=8339.0us expected=8334.4us +- 41.7us, error 0.1% (kms_flip:1367) DEBUG: name = flip last_ts = 100.695465 last_received_ts = 100.695389 last_seq = 648 current_ts = 100.703796 current_received_ts = 100.703659 current_seq = 649 count = 627 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.695465/648, current 100.703796/649: elapsed=8329.0us expected=8334.4us +- 41.7us, error 0.1% (kms_flip:1367) DEBUG: name = flip last_ts = 100.703796 last_received_ts = 100.703659 last_seq = 649 current_ts = 100.712128 current_received_ts = 100.711945 current_seq = 650 count = 628 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.703796/649, current 100.712128/650: elapsed=8337.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.712128 last_received_ts = 100.711945 last_seq = 650 current_ts = 100.720467 current_received_ts = 100.720398 current_seq = 651 count = 629 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.712128/650, current 100.720467/651: elapsed=8336.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.720467 last_received_ts = 100.720398 last_seq = 651 current_ts = 100.728798 current_received_ts = 100.728661 current_seq = 652 count = 630 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.720467/651, current 100.728798/652: elapsed=8331.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.728798 last_received_ts = 100.728661 last_seq = 652 current_ts = 100.737137 current_received_ts = 100.736954 current_seq = 653 count = 631 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.728798/652, current 100.737137/653: elapsed=8337.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.737137 last_received_ts = 100.736954 last_seq = 653 current_ts = 100.745468 current_received_ts = 100.745186 current_seq = 654 count = 632 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.737137/653, current 100.745468/654: elapsed=8332.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.745468 last_received_ts = 100.745186 last_seq = 654 current_ts = 100.753799 current_received_ts = 100.753517 current_seq = 655 count = 633 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.745468/654, current 100.753799/655: elapsed=8336.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.753799 last_received_ts = 100.753517 last_seq = 655 current_ts = 100.762138 current_received_ts = 100.761909 current_seq = 656 count = 634 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.753799/655, current 100.762138/656: elapsed=8336.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.762138 last_received_ts = 100.761909 last_seq = 656 current_ts = 100.770470 current_received_ts = 100.770340 current_seq = 657 count = 635 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.762138/656, current 100.770470/657: elapsed=8334.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.770470 last_received_ts = 100.770340 last_seq = 657 current_ts = 100.778801 current_received_ts = 100.778625 current_seq = 658 count = 636 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.770470/657, current 100.778801/658: elapsed=8332.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.778801 last_received_ts = 100.778625 last_seq = 658 current_ts = 100.787140 current_received_ts = 100.787079 current_seq = 659 count = 637 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.778801/658, current 100.787140/659: elapsed=8338.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.787140 last_received_ts = 100.787079 last_seq = 659 current_ts = 100.795479 current_received_ts = 100.795334 current_seq = 660 count = 638 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.787140/659, current 100.795479/660: elapsed=8335.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.795479 last_received_ts = 100.795334 last_seq = 660 current_ts = 100.803810 current_received_ts = 100.803741 current_seq = 661 count = 639 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.795479/660, current 100.803810/661: elapsed=8332.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.803810 last_received_ts = 100.803741 last_seq = 661 current_ts = 100.812149 current_received_ts = 100.811966 current_seq = 662 count = 640 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.803810/661, current 100.812149/662: elapsed=8339.0us expected=8334.4us +- 41.7us, error 0.1% (kms_flip:1367) DEBUG: name = flip last_ts = 100.812149 last_received_ts = 100.811966 last_seq = 662 current_ts = 100.820480 current_received_ts = 100.820305 current_seq = 663 count = 641 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.812149/662, current 100.820480/663: elapsed=8330.0us expected=8334.4us +- 41.7us, error 0.1% (kms_flip:1367) DEBUG: name = flip last_ts = 100.820480 last_received_ts = 100.820305 last_seq = 663 current_ts = 100.828819 current_received_ts = 100.828629 current_seq = 664 count = 642 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.820480/663, current 100.828819/664: elapsed=8339.0us expected=8334.4us +- 41.7us, error 0.1% (kms_flip:1367) DEBUG: name = flip last_ts = 100.828819 last_received_ts = 100.828629 last_seq = 664 current_ts = 100.837151 current_received_ts = 100.836967 current_seq = 665 count = 643 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.828819/664, current 100.837151/665: elapsed=8331.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.837151 last_received_ts = 100.836967 last_seq = 665 current_ts = 100.845482 current_received_ts = 100.845421 current_seq = 666 count = 644 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.837151/665, current 100.845482/666: elapsed=8336.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.845482 last_received_ts = 100.845421 last_seq = 666 current_ts = 100.853821 current_received_ts = 100.853676 current_seq = 667 count = 645 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.845482/666, current 100.853821/667: elapsed=8336.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.853821 last_received_ts = 100.853676 last_seq = 667 current_ts = 100.862152 current_received_ts = 100.861969 current_seq = 668 count = 646 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.853821/667, current 100.862152/668: elapsed=8331.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.862152 last_received_ts = 100.861969 last_seq = 668 current_ts = 100.870491 current_received_ts = 100.870422 current_seq = 669 count = 647 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.862152/668, current 100.870491/669: elapsed=8338.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.870491 last_received_ts = 100.870422 last_seq = 669 current_ts = 100.878822 current_received_ts = 100.878670 current_seq = 670 count = 648 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.870491/669, current 100.878822/670: elapsed=8332.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.878822 last_received_ts = 100.878670 last_seq = 670 current_ts = 100.887154 current_received_ts = 100.886971 current_seq = 671 count = 649 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.878822/670, current 100.887154/671: elapsed=8333.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.887154 last_received_ts = 100.886971 last_seq = 671 current_ts = 100.895485 current_received_ts = 100.895416 current_seq = 672 count = 650 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.887154/671, current 100.895485/672: elapsed=8334.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.895485 last_received_ts = 100.895416 last_seq = 672 current_ts = 100.903824 current_received_ts = 100.903595 current_seq = 673 count = 651 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.895485/672, current 100.903824/673: elapsed=8336.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.903824 last_received_ts = 100.903595 last_seq = 673 current_ts = 100.912163 current_received_ts = 100.912033 current_seq = 674 count = 652 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.903824/673, current 100.912163/674: elapsed=8337.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.912163 last_received_ts = 100.912033 last_seq = 674 current_ts = 100.920494 current_received_ts = 100.920341 current_seq = 675 count = 653 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.912163/674, current 100.920494/675: elapsed=8336.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.920494 last_received_ts = 100.920341 last_seq = 675 current_ts = 100.928833 current_received_ts = 100.928688 current_seq = 676 count = 654 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.920494/675, current 100.928833/676: elapsed=8335.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.928833 last_received_ts = 100.928688 last_seq = 676 current_ts = 100.937164 current_received_ts = 100.936897 current_seq = 677 count = 655 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.928833/676, current 100.937164/677: elapsed=8329.0us expected=8334.4us +- 41.7us, error 0.1% (kms_flip:1367) DEBUG: name = flip last_ts = 100.937164 last_received_ts = 100.936897 last_seq = 677 current_ts = 100.945496 current_received_ts = 100.945175 current_seq = 678 count = 656 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.937164/677, current 100.945496/678: elapsed=8336.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.945496 last_received_ts = 100.945175 last_seq = 678 current_ts = 100.953835 current_received_ts = 100.953552 current_seq = 679 count = 657 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.945496/678, current 100.953835/679: elapsed=8336.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.953835 last_received_ts = 100.953552 last_seq = 679 current_ts = 100.962173 current_received_ts = 100.961823 current_seq = 680 count = 658 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.953835/679, current 100.962173/680: elapsed=8337.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.962173 last_received_ts = 100.961823 last_seq = 680 current_ts = 100.970505 current_received_ts = 100.970161 current_seq = 681 count = 659 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.962173/680, current 100.970505/681: elapsed=8335.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.970505 last_received_ts = 100.970161 last_seq = 681 current_ts = 100.978836 current_received_ts = 100.978539 current_seq = 682 count = 660 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.970505/681, current 100.978836/682: elapsed=8332.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.978836 last_received_ts = 100.978539 last_seq = 682 current_ts = 100.987167 current_received_ts = 100.986786 current_seq = 683 count = 661 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.978836/682, current 100.987167/683: elapsed=8333.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.987167 last_received_ts = 100.986786 last_seq = 683 current_ts = 100.995506 current_received_ts = 100.995132 current_seq = 684 count = 662 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.987167/683, current 100.995506/684: elapsed=8337.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 100.995506 last_received_ts = 100.995132 last_seq = 684 current_ts = 101.003838 current_received_ts = 101.003494 current_seq = 685 count = 663 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 100.995506/684, current 101.003838/685: elapsed=8332.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 101.003838 last_received_ts = 101.003494 last_seq = 685 current_ts = 101.012177 current_received_ts = 101.012299 current_seq = 686 count = 664 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 101.003838/685, current 101.012177/686: elapsed=8339.0us expected=8334.4us +- 41.7us, error 0.1% (kms_flip:1367) DEBUG: name = flip last_ts = 101.012177 last_received_ts = 101.012299 last_seq = 686 current_ts = 101.020508 current_received_ts = 101.020149 current_seq = 687 count = 665 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 101.012177/686, current 101.020508/687: elapsed=8333.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 101.020508 last_received_ts = 101.020149 last_seq = 687 current_ts = 101.028847 current_received_ts = 101.028587 current_seq = 688 count = 666 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 101.020508/687, current 101.028847/688: elapsed=8332.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 101.028847 last_received_ts = 101.028587 last_seq = 688 current_ts = 101.037186 current_received_ts = 101.036888 current_seq = 689 count = 667 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 101.028847/688, current 101.037186/689: elapsed=8339.0us expected=8334.4us +- 41.7us, error 0.1% (kms_flip:1367) DEBUG: name = flip last_ts = 101.037186 last_received_ts = 101.036888 last_seq = 689 current_ts = 101.045517 current_received_ts = 101.045273 current_seq = 690 count = 668 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 101.037186/689, current 101.045517/690: elapsed=8333.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 101.045517 last_received_ts = 101.045273 last_seq = 690 current_ts = 101.053848 current_received_ts = 101.053711 current_seq = 691 count = 669 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 101.045517/690, current 101.053848/691: elapsed=8335.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 101.053848 last_received_ts = 101.053711 last_seq = 691 current_ts = 101.062187 current_received_ts = 101.062057 current_seq = 692 count = 670 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 101.053848/691, current 101.062187/692: elapsed=8336.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 101.062187 last_received_ts = 101.062057 last_seq = 692 current_ts = 101.070518 current_received_ts = 101.070351 current_seq = 693 count = 671 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 101.062187/692, current 101.070518/693: elapsed=8331.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 101.070518 last_received_ts = 101.070351 last_seq = 693 current_ts = 101.078850 current_received_ts = 101.078697 current_seq = 694 count = 672 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 101.070518/693, current 101.078850/694: elapsed=8336.0us expected=8334.4us +- 41.7us, error 0.0% (kms_flip:1367) DEBUG: name = flip last_ts = 101.078850 last_received_ts = 101.078697 last_seq = 694 current_ts = 101.086960 current_received_ts = 101.086884 current_seq = 695 count = 673 seq_step = 1 (kms_flip:1367) DEBUG: flip ts/seq: last 101.078850/694, current 101.086960/695: elapsed=8108.0us expected=8334.4us +- 41.7us, error 2.7% (kms_flip:1367) DEBUG: inconsistent flip ts/seq: last 101.078850/694, current 101.086960/695: elapsed=8108.0us expected=8334.4us (kms_flip:1367) DEBUG: expected 900, counted 673, encoder type 2 (kms_flip:1367) DEBUG: dropped frames, expected 900, counted 673, encoder type 2 (kms_flip:1367) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674: (kms_flip:1367) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon) (kms_flip:1367) igt_core-INFO: Stack trace: (kms_flip:1367) igt_core-INFO: #0 ../lib/igt_core.c:2051 __igt_fail_assert() (kms_flip:1367) igt_core-INFO: #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0() (kms_flip:1367) igt_core-INFO: #2 ../tests/kms_flip.c:1845 run_test() (kms_flip:1367) igt_core-INFO: #3 ../tests/kms_flip.c:2078 __igt_unique____real_main2001() (kms_flip:1367) igt_core-INFO: #4 ../tests/kms_flip.c:2001 main() (kms_flip:1367) igt_core-INFO: #5 [__libc_init_first+0x8a] (kms_flip:1367) igt_core-INFO: #6 [__libc_start_main+0x8b] (kms_flip:1367) igt_core-INFO: #7 [_start+0x25] **** END **** Dynamic subtest D-HDMI-A1: FAIL (9.157s) Subtest plain-flip-ts-check: FAIL (32.557s) (kms_flip:1367) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2209: (kms_flip:1367) igt_kms-CRITICAL: Failed assertion: found_it (kms_flip:1367) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor (kms_flip:1367) igt_kms-CRITICAL: DPMS property not found on 313 |
| Dmesg |
<6> [141.758317] [IGT] kms_flip: starting dynamic subtest D-HDMI-A1
<7> [141.758559] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:362]
<7> [141.758599] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:363]
<7> [141.794081] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:100:pipe A]
<7> [141.794463] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:170:pipe B]
<7> [141.794706] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:240:pipe C]
<7> [141.794845] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.enable (expected yes, found no)
<7> [141.795054] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.active (expected yes, found no)
<7> [141.795199] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in cpu_transcoder (expected 2, found -1)
<7> [141.795386] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in lane_count (expected 4, found 0)
<7> [141.795527] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [141.795670] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [141.795810] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [141.795948] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2080, found 0)
<7> [141.796088] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [141.796227] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2080, found 0)
<7> [141.796380] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1968, found 0)
<7> [141.796521] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2000, found 0)
<7> [141.796660] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [141.796799] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [141.796937] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [141.797080] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [141.797221] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1144, found 0)
<7> [141.797419] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1144, found 0)
<7> [141.797558] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [141.797697] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2080, found 0)
<7> [141.797835] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [141.797973] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2080, found 0)
<7> [141.798110] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1968, found 0)
<7> [141.798246] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2000, found 0)
<7> [141.798528] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [141.798684] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [141.798820] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [141.798951] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [141.799089] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1144, found 0)
<7> [141.799229] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1144, found 0)
<7> [141.799376] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [141.799515] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [141.799737] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [141.799993] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [141.800251] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [141.800526] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [141.800779] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 285500, found 0)
<7> [141.801029] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 285500, found 0)
<7> [141.801244] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in port_clock (expected 285500, found 0)
<7> [141.801438] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [141.801618] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in avi infoframe
<7> [141.801798] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [141.801978] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [141.801980] i915 0000:00:02.0: colorspace: RGB
<7> [141.801982] i915 0000:00:02.0: scan mode: Underscan
<7> [141.801984] i915 0000:00:02.0: colorimetry: No Data
<7> [141.801986] i915 0000:00:02.0: picture aspect: No Data
<7> [141.801988] i915 0000:00:02.0: active aspect: Same as Picture
<7> [141.801989] i915 0000:00:02.0: itc: No Data
<7> [141.801991] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [141.801993] i915 0000:00:02.0: quantization range: Full
<7> [141.801994] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [141.801996] i915 0000:00:02.0: video code: 0
<7> [141.801998] i915 0000:00:02.0: ycc quantization range: Full
<7> [141.802000] i915 0000:00:02.0: hdmi content type: Graphics
<7> [141.802001] i915 0000:00:02.0: pixel repeat: 0
<7> [141.802003] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [141.802005] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [141.802183] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in spd infoframe
<7> [141.802372] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [141.802555] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [141.802557] i915 0000:00:02.0: vendor: Intel
<7> [141.802559] i915 0000:00:02.0: product: Integrated gfx
<7> [141.802560] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [141.802562] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [141.802746] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:240:pipe C] fastset requirement not met in hdmi infoframe
<7> [141.802929] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [141.803110] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [141.803112] i915 0000:00:02.0: empty frame
<7> [141.803114] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [141.803302] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:240:pipe C] fastset requirement not met, forcing full modeset
<7> [141.803485] i915 0000:00:02.0: [drm:intel_unreference_shared_dpll_crtc [i915]] [CRTC:240:pipe C] releasing DPLL 0
<7> [141.803689] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [141.803862] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:240:pipe C] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x4 -> 0x0
<7> [141.804071] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:235:cursor C] ddb (1993 - 2048) -> ( 0 - 0), size 55 -> 0
<7> [141.804242] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] Modeset required for cdclk change
<7> [141.804392] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz
<7> [141.804534] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [141.804675] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:240:pipe C] enable: no [modeset]
<7> [141.804815] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:172:plane 1C] fb: [NOFB], visible: no
<7> [141.804954] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:181:plane 2C] fb: [NOFB], visible: no
<7> [141.805093] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:190:plane 3C] fb: [NOFB], visible: no
<7> [141.805233] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:199:plane 4C] fb: [NOFB], visible: no
<7> [141.805383] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:208:plane 5C] fb: [NOFB], visible: no
<7> [141.805524] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:217:plane 6C] fb: [NOFB], visible: no
<7> [141.805663] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:226:plane 7C] fb: [NOFB], visible: no
<7> [141.805801] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:235:cursor C] fb: [NOFB], visible: no
<7> [141.805992] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:313:HDMI-A-1][ENCODER:312:DDI B/PHY B] Disable audio codec on [CRTC:240:pipe C]
<7> [141.816142] i915 0000:00:02.0: [drm:i915_audio_component_get_eld [i915]] Not valid for port B
<7> [141.817807] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:313:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [141.818409] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [141.818535] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [141.819100] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [141.819229] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:313:HDMI-A-1] Failed to read TMDS config: -6
<7> [141.819239] i915 0000:00:02.0: [drm:intel_encoders_disable [i915]] [CONNECTOR:313:HDMI-A-1] Failed to reset sink scrambling/TMDS bit clock ratio
<7> [141.819497] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe C
<7> [141.824361] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [141.824537] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disable DPLL 0 (active 0x4, on? 1) for [CRTC:240:pipe C]
<7> [141.824704] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disabling DPLL 0
<7> [141.824859] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 172800 kHz, VCO 345600 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [141.825125] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:312:DDI B/PHY B]
<7> [141.825280] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:322:DDI TC1/PHY TC1]
<7> [141.825436] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:324:DP-MST A]
<7> [141.825576] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:325:DP-MST B]
<7> [141.825713] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:326:DP-MST C]
<7> [141.825850] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:327:DP-MST D]
<7> [141.825985] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:332:DDI TC2/PHY TC2]
<7> [141.826122] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:334:DP-MST A]
<7> [141.826263] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:335:DP-MST B]
<7> [141.826413] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:336:DP-MST C]
<7> [141.826551] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:337:DP-MST D]
<7> [141.826689] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:341:DDI TC3/PHY TC3]
<7> [141.826826] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:343:DP-MST A]
<7> [141.826962] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:344:DP-MST B]
<7> [141.827096] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:345:DP-MST C]
<7> [141.827239] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:346:DP-MST D]
<7> [141.827391] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:350:DDI TC4/PHY TC4]
<7> [141.827537] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:352:DP-MST A]
<7> [141.827684] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:353:DP-MST B]
<7> [141.827829] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:354:DP-MST C]
<7> [141.827974] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:355:DP-MST D]
<7> [141.828117] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:313:HDMI-A-1]
<7> [141.828397] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [141.828593] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_4
<7> [141.828766] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [141.828938] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [141.829104] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:240:pipe C]
<7> [141.829465] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:310:pipe D]
<7> [141.829625] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:310:pipe D]
<7> [141.829657] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:313:HDMI-A-1]
<7> [141.829699] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:313:HDMI-A-1] Limiting display bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [141.829865] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [141.830013] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:310:pipe D] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [141.830167] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:312:DDI B/PHY B] [CRTC:310:pipe D]
<7> [141.830304] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.enable (expected no, found yes)
<7> [141.830445] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.active (expected no, found yes)
<7> [141.830586] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in cpu_transcoder (expected -1, found 3)
<7> [141.830727] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in lane_count (expected 0, found 4)
<7> [141.830867] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [141.831007] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [141.831148] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [141.831296] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2080)
<7> [141.831438] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [141.831578] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2080)
<7> [141.831716] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1968)
<7> [141.831855] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2000)
<7> [141.831994] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [141.832130] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [141.832269] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [141.832418] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [141.832554] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1144)
<7> [141.832692] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1144)
<7> [141.832828] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [141.832965] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2080)
<7> [141.833101] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [141.833236] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2080)
<7> [141.833382] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1968)
<7> [141.833519] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2000)
<7> [141.833653] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [141.833790] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [141.833926] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [141.834067] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [141.834208] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1144)
<7> [141.834355] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1144)
<7> [141.834494] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [141.834633] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [141.834771] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [141.834911] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [141.835049] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [141.835188] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in dpll_hw_state
<7> [141.835331] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [141.835358] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [141.835476] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: cfgcr0: 0x0, cfgcr1: 0x0, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [141.835541] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [141.835620] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [141.835707] i915 0000:00:02.0: [drm:i915_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [141.835760] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: cfgcr0: 0x1801be, cfgcr1: 0x488, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [141.835903] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [141.836041] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 285500)
<7> [141.836179] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 285500)
<7> [141.836322] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in port_clock (expected 0, found 285500)
<7> [141.836460] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [141.836599] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in avi infoframe
<7> [141.836737] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [141.836874] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [141.837009] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [141.837011] i915 0000:00:02.0: colorspace: RGB
<7> [141.837012] i915 0000:00:02.0: scan mode: Underscan
<7> [141.837014] i915 0000:00:02.0: colorimetry: No Data
<7> [141.837015] i915 0000:00:02.0: picture aspect: No Data
<7> [141.837016] i915 0000:00:02.0: active aspect: Same as Picture
<7> [141.837018] i915 0000:00:02.0: itc: No Data
<7> [141.837019] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [141.837021] i915 0000:00:02.0: quantization range: Full
<7> [141.837022] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [141.837023] i915 0000:00:02.0: video code: 0
<7> [141.837025] i915 0000:00:02.0: ycc quantization range: Full
<7> [141.837026] i915 0000:00:02.0: hdmi content type: Graphics
<7> [141.837027] i915 0000:00:02.0: pixel repeat: 0
<7> [141.837029] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [141.837030] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in spd infoframe
<7> [141.837168] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [141.837314] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [141.837456] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [141.837458] i915 0000:00:02.0: vendor: Intel
<7> [141.837459] i915 0000:00:02.0: product: Integrated gfx
<7> [141.837461] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [141.837462] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hdmi infoframe
<7> [141.837605] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [141.837745] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [141.837882] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [141.837884] i915 0000:00:02.0: empty frame
<7> [141.837886] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:310:pipe D] fastset requirement not met, forcing full modeset
<7> [141.838044] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [141.838178] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:310:pipe D] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x8
<7> [141.838345] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:242:plane 1D] ddb ( 0 - 0) -> ( 0 - 1993), size 0 -> 1993
<7> [141.838476] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:305:cursor D] ddb ( 0 - 0) -> (1993 - 2048), size 0 -> 55
<7> [141.838609] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:242:plane 1D] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [141.838742] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:242:plane 1D] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0
<7> [141.838874] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:242:plane 1D] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0
<7> [141.839006] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:242:plane 1D] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 143, 143, 143, 143, 196, 284, 284, 31, 0, 0
<7> [141.839140] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:310:pipe D] data rate 1142000 num active planes 1
<7> [141.839283] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 1142
<7> [141.839440] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [141.839592] i915 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [i915]] [PLANE:242:plane 1D] min cdclk (142750 kHz) > [CRTC:310:pipe D] min cdclk (0 kHz)
<7> [141.839735] i915 0000:00:02.0: [drm:intel_bw_calc_min_cdclk [i915]] new bandwidth min cdclk (22305 kHz) > old min cdclk (0 kHz)
<7> [141.839900] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] Modeset required for cdclk change
<7> [141.840045] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [141.840187] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [141.840337] i915 0000:00:02.0: [drm:intel_find_shared_dpll [i915]] [CRTC:310:pipe D] allocated DPLL 0
<7> [141.840487] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc [i915]] [CRTC:310:pipe D] reserving DPLL 0
<7> [141.840629] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:310:pipe D] enable: yes [modeset]
<7> [141.840771] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [141.840912] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: D, pipe bpp: 24, dithering: 0
<7> [141.841052] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [141.841190] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [141.841336] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [141.841483] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [141.841628] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0
<7> [141.841772] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [141.841914] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x0
<7> [141.842056] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [141.842057] i915 0000:00:02.0: colorspace: RGB
<7> [141.842058] i915 0000:00:02.0: scan mode: Underscan
<7> [141.842060] i915 0000:00:02.0: colorimetry: No Data
<7> [141.842061] i915 0000:00:02.0: picture aspect: No Data
<7> [141.842062] i915 0000:00:02.0: active aspect: Same as Picture
<7> [141.842064] i915 0000:00:02.0: itc: No Data
<7> [141.842065] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [141.842066] i915 0000:00:02.0: quantization range: Full
<7> [141.842067] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [141.842069] i915 0000:00:02.0: video code: 0
<7> [141.842070] i915 0000:00:02.0: ycc quantization range: Full
<7> [141.842071] i915 0000:00:02.0: hdmi content type: Graphics
<7> [141.842072] i915 0000:00:02.0: pixel repeat: 0
<7> [141.842074] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [141.842075] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [141.842077] i915 0000:00:02.0: vendor: Intel
<7> [141.842078] i915 0000:00:02.0: product: Integrated gfx
<7> [141.842079] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [141.842081] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [141.842082] i915 0000:00:02.0: empty frame
<7> [141.842084] ELD: 10 00 0f 00 6d a2 00 7f 00 00 00 00 00 00 00 00
<7> [141.842085] ELD: 0d 82 34 12 66 69 74 48 65 61 64 6c 65 73 73 47
<7> [141.842087] ELD: 53 7f 7f 28 0f 7f 07 17 7f ff 3f 7f ff 47 7f ff
<7> [141.842088] ELD: 5f 7f 82 7f 7f 00 7f 7f 51 7f 7f 30 77 7f 03 00
<7> [141.842089] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, vmin: 0, vmax: 0, pipeline full: 0, guardband: 0 flipline: 0, vmin vblank: -1, vmax vblank: -2
<7> [141.842229] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5
<7> [141.842380] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5
<7> [141.842520] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=285500, hd=1920 hb=1920-2080 hs=1968-2000 ht=2080, vd=1080 vb=1080-1144 vs=1083-1088 vt=1144, flags=0x5
<7> [141.842661] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x40 0x5
<7> [141.842803] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=285500, hd=1920 hb=1920-2080 hs=1968-2000 ht=2080, vd=1080 vb=1080-1144 vs=1083-1088 vt=1144, flags=0x5
<7> [141.842942] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 285500, pipe src: 1920x1080+0+0, pixel rate 285500
<7> [141.843080] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [141.843217] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [141.843365] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [141.843505] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [141.843644] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1801be, cfgcr1: 0x488, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [141.843787] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [141.843929] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [141.844069] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [141.844211] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [141.844359] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [141.844502] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [141.844641] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [141.844778] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [141.844918] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [141.845058] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [141.845197] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [141.845342] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [141.845488] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:242:plane 1D] fb: [FB:362] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [141.845630] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [141.845768] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [141.845908] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:251:plane 2D] fb: [NOFB], visible: no
<7> [141.846049] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:260:plane 3D] fb: [NOFB], visible: no
<7> [141.846186] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:269:plane 4D] fb: [NOFB], visible: no
<7> [141.846334] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:278:plane 5D] fb: [NOFB], visible: no
<7> [141.846479] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:287:plane 6D] fb: [NOFB], visible: no
<7> [141.846622] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:296:plane 7D] fb: [NOFB], visible: no
<7> [141.846762] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:305:cursor D] fb: [NOFB], visible: no
<7> [141.847471] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_4
<7> [141.847641] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_5
<7> [141.847814] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 192000 kHz, VCO 384000 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [141.848033] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:312:DDI B/PHY B]
<7> [141.848170] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:322:DDI TC1/PHY TC1]
<7> [141.848313] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:324:DP-MST A]
<7> [141.848447] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:325:DP-MST B]
<7> [141.848578] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:326:DP-MST C]
<7> [141.848711] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:327:DP-MST D]
<7> [141.848846] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:332:DDI TC2/PHY TC2]
<7> [141.848982] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:334:DP-MST A]
<7> [141.849115] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:335:DP-MST B]
<7> [141.849247] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:336:DP-MST C]
<7> [141.849389] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:337:DP-MST D]
<7> [141.849522] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:341:DDI TC3/PHY TC3]
<7> [141.849653] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:343:DP-MST A]
<7> [141.849784] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:344:DP-MST B]
<7> [141.849913] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:345:DP-MST C]
<7> [141.850048] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:346:DP-MST D]
<7> [141.850185] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:350:DDI TC4/PHY TC4]
<7> [141.850324] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:352:DP-MST A]
<7> [141.850458] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:353:DP-MST B]
<7> [141.850590] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:354:DP-MST C]
<7> [141.850722] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:355:DP-MST D]
<7> [141.850949] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [141.851126] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enable DPLL 0 (active 0x8, on? 0) for [CRTC:310:pipe D]
<7> [141.851265] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling DPLL 0
<7> [141.851475] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [141.851694] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe D
<7> [141.852364] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:313:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [141.852745] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [141.852868] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [141.853458] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [141.853586] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:313:HDMI-A-1] Failed to read TMDS config: -6
<7> [141.853596] i915 0000:00:02.0: [drm:intel_enable_ddi [i915]] [CONNECTOR:313:HDMI-A-1] Failed to configure sink scrambling/TMDS bit clock ratio
<7> [141.862258] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:313:HDMI-A-1][ENCODER:312:DDI B/PHY B] Enable audio codec on [CRTC:310:pipe D], 64 bytes ELD
<7> [141.870601] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] HDMI audio pixel clock setting for 285500 not found, falling back to defaults
<7> [141.870744] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)
<7> [141.870876] i915 0000:00:02.0: [drm:hsw_hdmi_audio_config_update.isra.0 [i915]] using automatic N
<6> [141.871011] snd_hda_codec_hdmi hdaudioC0D2: HDMI: audio coding xtype 5 not expected
<6> [141.871013] snd_hda_codec_hdmi hdaudioC0D2: HDMI: audio coding xtype 0 not expected
<6> [141.871015] snd_hda_codec_hdmi hdaudioC0D2: HDMI: audio coding xtype 10 not expected
<6> [141.871016] snd_hda_codec_hdmi hdaudioC0D2: HDMI: audio coding xtype 6 not expected
<7> [141.871140] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:313:HDMI-A-1]
<7> [141.871359] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:310:pipe D]
<7> [141.911711] i915 0000:00:02.0: [drm:i915_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<6> [150.915074] [IGT] kms_flip: finished subtest D-HDMI-A1, FAIL
<6> [150.919881] [IGT] kms_flip: finished subtest plain-flip-ts-check, FAIL
<7> [150.926730] i915 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:363] from all active usage due to RMFB ioctl
<7> [150.927049] i915 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:242:plane 1D] because [FB:363] is removed
<7> [150.927197] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:242:plane 1D] ddb ( 0 - 1993) -> ( 0 - 0), size 1993 -> 0
<7> [150.927505] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:242:plane 1D] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [150.927813] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:242:plane 1D] lines 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [150.928109] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:242:plane 1D] blocks 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [150.928388] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:242:plane 1D] min_ddb 19, 143, 143, 143, 143, 196, 284, 284, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [150.928660] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:310:pipe D] data rate 0 num active planes 0
<7> [150.928993] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 0
<6> [150.931454] [IGT] kms_flip: exiting, ret=98
<7> [150.946831] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:313:HDMI-A-1] Limiting display bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [150.947039] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [150.947178] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:100:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [150.947338] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:312:DDI B/PHY B] [CRTC:100:pipe A]
<7> [150.947467] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [150.947596] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [150.947734] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [150.947860] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [150.947997] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [150.948194] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [150.948431] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [150.948638] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2080)
<7> [150.948840] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [150.949045] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2080)
<7> [150.949280] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1968)
<7> [150.949476] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2000)
<7> [150.949676] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [150.949870] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [150.950068] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [150.950298] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [150.950498] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1144)
<7> [150.950694] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1144)
<7> [150.950890] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [150.951088] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2080)
<7> [150.951310] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [150.951509] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2080)
<7> [150.951706] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1968)
<7> [150.951904] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2000)
<7> [150.952106] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [150.952429] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [150.952618] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [150.952799] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [150.952978] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1144)
<7> [150.953180] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1144)
<7> [150.953384] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [150.953586] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [150.953785] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [150.953987] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [150.954200] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [150.954399] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [150.954596] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 285500)
<7> [150.954793] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 285500)
<7> [150.954991] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in port_clock (expected 0, found 285500)
<7> [150.955202] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [150.955399] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in avi infoframe
<7> [150.955589] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [150.955799] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [150.955998] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [150.956002] i915 0000:00:02.0: colorspace: RGB
<7> [150.956005] i915 0000:00:02.0: scan mode: Underscan
<7> [150.956009] i915 0000:00:02.0: colorimetry: No Data
<7> [150.956012] i915 0000:00:02.0: picture aspect: No Data
<7> [150.956014] i915 0000:00:02.0: active aspect: Same as Picture
<7> [150.956017] i915 0000:00:02.0: itc: No Data
<7> [150.956020] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [150.956023] i915 0000:00:02.0: quantization range: Full
<7> [150.956026] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [150.956028] i915 0000:00:02.0: video code: 0
<7> [150.956031] i915 0000:00:02.0: ycc quantization range: Full
<7> [150.956034] i915 0000:00:02.0: hdmi content type: Graphics
<7> [150.956037] i915 0000:00:02.0: pixel repeat: 0
<7> [150.956040] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [150.956043] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in spd infoframe
<7> [150.956527] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [150.956731] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [150.956926] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [150.956930] i915 0000:00:02.0: vendor: Intel
<7> [150.956933] i915 0000:00:02.0: product: Integrated gfx
<7> [150.956936] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [150.956939] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:100:pipe A] fastset requirement not met in hdmi infoframe
<7> [150.957136] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [150.957354] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [150.957553] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [150.957557] i915 0000:00:02.0: empty frame
<7> [150.957560] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:100:pipe A] fastset requirement not met, forcing full modeset
<7> [150.957739] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.enable (expected yes, found no)
<7> [150.957918] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.active (expected yes, found no)
<7> [150.958101] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in cpu_transcoder (expected 3, found -1)
<7> [150.958309] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in lane_count (expected 4, found 0)
<7> [150.958512] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [150.958693] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [150.958872] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [150.959051] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2080, found 0)
<7> [150.959232] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [150.959451] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2080, found 0)
<7> [150.959651] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1968, found 0)
<7> [150.959849] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2000, found 0)
<7> [150.960046] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [150.960245] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [150.960480] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [150.960686] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [150.960888] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1144, found 0)
<7> [150.961088] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1144, found 0)
<7> [150.961292] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [150.961484] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2080, found 0)
<7> [150.961662] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [150.961841] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2080, found 0)
<7> [150.962018] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1968, found 0)
<7> [150.962196] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2000, found 0)
<7> [150.962408] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [150.962609] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [150.962787] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [150.962966] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [150.963145] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1144, found 0)
<7> [150.963339] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1144, found 0)
<7> [150.963534] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [150.963733] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [150.963929] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [150.964129] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [150.964598] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [150.964806] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [150.965006] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 285500, found 0)
<7> [150.965206] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 285500, found 0)
<7> [150.965422] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in port_clock (expected 285500, found 0)
<7> [150.965620] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [150.965818] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in avi infoframe
<7> [150.966015] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [150.966211] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [150.966214] i915 0000:00:02.0: colorspace: RGB
<7> [150.966218] i915 0000:00:02.0: scan mode: Underscan
<7> [150.966220] i915 0000:00:02.0: colorimetry: No Data
<7> [150.966223] i915 0000:00:02.0: picture aspect: No Data
<7> [150.966226] i915 0000:00:02.0: active aspect: Same as Picture
<7> [150.966229] i915 0000:00:02.0: itc: No Data
<7> [150.966231] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [150.966234] i915 0000:00:02.0: quantization range: Full
<7> [150.966237] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [150.966240] i915 0000:00:02.0: video code: 0
<7> [150.966242] i915 0000:00:02.0: ycc quantization range: Full
<7> [150.966245] i915 0000:00:02.0: hdmi content type: Graphics
<7> [150.966248] i915 0000:00:02.0: pixel repeat: 0
<7> [150.966251] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [150.966254] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [150.966474] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in spd infoframe
<7> [150.966670] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [150.966872] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [150.966876] i915 0000:00:02.0: vendor: Intel
<7> [150.966879] i915 0000:00:02.0: product: Integrated gfx
<7> [150.966881] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [150.966884] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [150.967083] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:310:pipe D] fastset requirement not met in hdmi infoframe
<7> [150.967286] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [150.967506] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [150.967509] i915 0000:00:02.0: empty frame
<7> [150.967512] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [150.967712] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:310:pipe D] fastset requirement not met, forcing full modeset
<7> [150.967913] i915 0000:00:02.0: [drm:intel_unreference_shared_dpll_crtc [i915]] [CRTC:310:pipe D] releasing DPLL 0
<7> [150.968162] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:100:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x8 -> 0x1
<7> [150.968352] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:310:pipe D] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x8 -> 0x1
<7> [150.968605] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] ddb ( 0 - 0) -> ( 0 - 1993), size 0 -> 1993
<7> [150.968792] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:95:cursor A] ddb ( 0 - 0) -> (1993 - 2048), size 0 -> 55
<7> [150.968980] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32: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> [150.969173] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0
<7> [150.969363] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0
<7> [150.969574] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 143, 143, 143, 143, 196, 284, 284, 31, 0, 0
<7> [150.969769] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:305:cursor D] ddb (1993 - 2048) -> ( 0 - 0), size 55 -> 0
<7> [150.969958] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:100:pipe A] data rate 1142000 num active planes 1
<7> [150.970166] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 1142
<7> [150.970368] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [150.970621] i915 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [i915]] [PLANE:32:plane 1A] min cdclk (142750 kHz) > [CRTC:100:pipe A] min cdclk (0 kHz)
<7> [150.970832] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [150.971033] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [150.971232] i915 0000:00:02.0: [drm:intel_find_shared_dpll [i915]] [CRTC:100:pipe A] allocated DPLL 0
<7> [150.971432] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc [i915]] [CRTC:100:pipe A] reserving DPLL 0
<7> [150.971667] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:100:pipe A] enable: yes [modeset]
<7> [150.971872] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [150.972057] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [150.972238] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [150.972417] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [150.972635] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [150.972841] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [150.973026] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0
<7> [150.973205] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [150.973388] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x0
<7> [150.973601] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [150.973604] i915 0000:00:02.0: colorspace: RGB
<7> [150.973606] i915 0000:00:02.0: scan mode: Underscan
<7> [150.973609] i915 0000:00:02.0: colorimetry: No Data
<7> [150.973613] i915 0000:00:02.0: picture aspect: No Data
<7> [150.973616] i915 0000:00:02.0: active aspect: Same as Picture
<7> [150.973631] i915 0000:00:02.0: itc: No Data
<7> [150.973634] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [150.973636] i915 0000:00:02.0: quantization range: Full
<7> [150.973639] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [150.973642] i915 0000:00:02.0: video code: 0
<7> [150.973645] i915 0000:00:02.0: ycc quantization range: Full
<7> [150.973648] i915 0000:00:02.0: hdmi content type: Graphics
<7> [150.973650] i915 0000:00:02.0: pixel repeat: 0
<7> [150.973653] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [150.973657] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [150.973660] i915 0000:00:02.0: vendor: Intel
<7> [150.973663] i915 0000:00:02.0: product: Integrated gfx
<7> [150.973665] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [150.973668] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [150.973671] i915 0000:00:02.0: empty frame
<7> [150.973674] ELD: 10 00 0f 00 6d a2 00 7f 00 00 00 00 00 00 00 00
<7> [150.973677] ELD: 0d 82 34 12 66 69 74 48 65 61 64 6c 65 73 73 47
<7> [150.973679] ELD: 53 7f 7f 28 0f 7f 07 17 7f ff 3f 7f ff 47 7f ff
<7> [150.973682] ELD: 5f 7f 82 7f 7f 00 7f 7f 51 7f 7f 30 77 7f 03 00
<7> [150.973685] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, vmin: 0, vmax: 0, pipeline full: 0, guardband: 0 flipline: 0, vmin vblank: -1, vmax vblank: -2
<7> [150.973881] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5
<7> [150.974066] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5
<7> [150.974252] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=285500, hd=1920 hb=1920-2080 hs=1968-2000 ht=2080, vd=1080 vb=1080-1144 vs=1083-1088 vt=1144, flags=0x5
<7> [150.974435] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x40 0x5
<7> [150.974651] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=285500, hd=1920 hb=1920-2080 hs=1968-2000 ht=2080, vd=1080 vb=1080-1144 vs=1083-1088 vt=1144, flags=0x5
<7> [150.974852] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 285500, pipe src: 1920x1080+0+0, pixel rate 285500
<7> [150.975033] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [150.975211] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [150.975389] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [150.975590] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [150.975783] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1801be, cfgcr1: 0x488, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [150.975967] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [150.976145] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [150.976325] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [150.976504] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [150.976726] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [150.976930] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [150.977131] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [150.977333] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [150.977535] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [150.977753] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [150.977954] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [150.978150] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [150.978349] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:plane 1A] fb: [FB:360] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [150.978549] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [150.978764] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [150.978970] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:41:plane 2A] fb: [NOFB], visible: no
<7> [150.979172] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:50:plane 3A] fb: [NOFB], visible: no
<7> [150.979373] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:59:plane 4A] fb: [NOFB], visible: no
<7> [150.979570] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:68:plane 5A] fb: [NOFB], visible: no
<7> [150.979791] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:77:plane 6A] fb: [NOFB], visible: no
<7> [150.979994] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:86:plane 7A] fb: [NOFB], visible: no
<7> [150.980191] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:cursor A] fb: [NOFB], visible: no
<7> [150.980391] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:310:pipe D] enable: no [modeset]
<7> [150.980590] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:242:plane 1D] fb: [NOFB], visible: no
<7> [150.980821] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:251:plane 2D] fb: [NOFB], visible: no
<7> [150.981027] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:260:plane 3D] fb: [NOFB], visible: no
<7> [150.981230] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:269:plane 4D] fb: [NOFB], visible: no
<7> [150.981432] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:278:plane 5D] fb: [NOFB], visible: no
<7> [150.981636] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:287:plane 6D] fb: [NOFB], visible: no
<7> [150.981870] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:296:plane 7D] fb: [NOFB], visible: no
<7> [150.982068] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:305:cursor D] fb: [NOFB], visible: no
<7> [150.982492] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:313:HDMI-A-1][ENCODER:312:DDI B/PHY B] Disable audio codec on [CRTC:310:pipe D]
<7> [150.996932] i915 0000:00:02.0: [drm:i915_audio_component_get_eld [i915]] Not valid for port B
<7> [150.999982] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:313:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [151.000663] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [151.000844] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [151.001659] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [151.001845] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:313:HDMI-A-1] Failed to read TMDS config: -6
<7> [151.001860] i915 0000:00:02.0: [drm:intel_encoders_disable [i915]] [CONNECTOR:313:HDMI-A-1] Failed to reset sink scrambling/TMDS bit clock ratio
<7> [151.002333] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe D
<7> [151.007127] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [151.007445] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disable DPLL 0 (active 0x8, on? 1) for [CRTC:310:pipe D]
<7> [151.007712] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disabling DPLL 0
<7> [151.007906] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:312:DDI B/PHY B]
<7> [151.008084] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:322:DDI TC1/PHY TC1]
<7> [151.008312] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:324:DP-MST A]
<7> [151.008504] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:325:DP-MST B]
<7> [151.008696] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:326:DP-MST C]
<7> [151.008922] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:327:DP-MST D]
<7> [151.009110] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:332:DDI TC2/PHY TC2]
<7> [151.009329] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:334:DP-MST A]
<7> [151.009519] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:335:DP-MST B]
<7> [151.009709] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:336:DP-MST C]
<7> [151.009898] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:337:DP-MST D]
<7> [151.010088] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:341:DDI TC3/PHY TC3]
<7> [151.010303] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:343:DP-MST A]
<7> [151.010493] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:344:DP-MST B]
<7> [151.010678] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:345:DP-MST C]
<7> [151.010869] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:346:DP-MST D]
<7> [151.011061] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:350:DDI TC4/PHY TC4]
<7> [151.011273] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:352:DP-MST A]
<7> [151.011464] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:353:DP-MST B]
<7> [151.011652] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:354:DP-MST C]
<7> [151.011840] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:355:DP-MST D]
<7> [151.012263] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:100:pipe A]
<7> [151.012463] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling DPLL 0
<7> [151.012771] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [151.013084] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [151.013508] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:313:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [151.014152] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [151.014368] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [151.015022] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [151.015211] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:313:HDMI-A-1] Failed to read TMDS config: -6
<7> [151.015227] i915 0000:00:02.0: [drm:intel_enable_ddi [i915]] [CONNECTOR:313:HDMI-A-1] Failed to configure sink scrambling/TMDS bit clock ratio
<7> [151.022431] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [151.022575] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:32:plane 1A]
<7> [151.024000] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:313:HDMI-A-1][ENCODER:312:DDI B/PHY B] Enable audio codec on [CRTC:100:pipe A], 64 bytes ELD
<7> [151.025669] i915 0000:00:02.0: [drm:i915_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [151.032360] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] HDMI audio pixel clock setting for 285500 not found, falling back to defaults
<7> [151.032507] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)
<7> [151.032670] i915 0000:00:02.0: [drm:hsw_hdmi_audio_config_update.isra.0 [i915]] using automatic N
<6> [151.032885] snd_hda_codec_hdmi hdaudioC0D2: HDMI: audio coding xtype 5 not expected
<6> [151.032889] snd_hda_codec_hdmi hdaudioC0D2: HDMI: audio coding xtype 0 not expected
<6> [151.032892] snd_hda_codec_hdmi hdaudioC0D2: HDMI: audio coding xtype 10 not expected
<6> [151.032895] snd_hda_codec_hdmi hdaudioC0D2: HDMI: audio coding xtype 6 not expected
<7> [151.033082] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:313:HDMI-A-1]
<7> [151.033415] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:100:pipe A]
<7> [151.034448] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_5
<7> [151.034841] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:310:pipe D]
<7> [151.035310] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_4
<7> [151.035857] i915 0000:00:02.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
|
