Result:
i915_display_info0 igt_runner0 results0.json results0-xe-load.json guc_logs0.tar boot0 dmesg0
| Detail | Value |
|---|---|
| Duration | 4.26 seconds |
| Hostname |
shard-bmg-10 |
| Igt-Version |
IGT-Version: 2.4-g5b279a8b7 (x86_64) (Linux: 7.0.0-lgci-xe-xe-4917-ff84b38d86b994ebb-debug+ x86_64) |
| Out |
Starting dynamic subtest: AC-DP2-HDMI-A3 1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 Stack trace: #0 ../lib/igt_core.c:2075 __igt_fail_assert() #1 ../tests/kms_flip.c:926 run_test_on_crtc_set() #2 ../tests/kms_flip.c:2029 run_pair() #3 ../tests/kms_flip.c:2193 __igt_unique____real_main2105() #4 ../tests/kms_flip.c:2105 main() #5 [__libc_init_first+0x8a] #6 [__libc_start_main+0x8b] #7 [_start+0x25] Dynamic subtest AC-DP2-HDMI-A3: FAIL (4.260s) |
| Err |
Starting dynamic subtest: AC-DP2-HDMI-A3 (kms_flip:4838) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931: (kms_flip:4838) CRITICAL: Failed assertion: end - start < 500 Dynamic subtest AC-DP2-HDMI-A3 failed. **** DEBUG **** (kms_flip:4838) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0 (kms_flip:4838) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0 (kms_flip:4838) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0 (kms_flip:4838) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0 (kms_flip:4838) DEBUG: name = flip last_ts = 0.000000 last_received_ts = 0.000000 last_seq = 0 current_ts = 181.413132 current_received_ts = 181.412842 current_seq = 413 count = 0 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 38us (kms_flip:4838) DEBUG: name = flip last_ts = 181.413132 last_received_ts = 181.412842 last_seq = 413 current_ts = 181.429794 current_received_ts = 181.429428 current_seq = 414 count = 1 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 38us (kms_flip:4838) DEBUG: name = flip last_ts = 181.429794 last_received_ts = 181.429428 last_seq = 414 current_ts = 181.446472 current_received_ts = 181.446152 current_seq = 415 count = 2 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 27us (kms_flip:4838) DEBUG: name = flip last_ts = 181.446472 last_received_ts = 181.446152 last_seq = 415 current_ts = 181.463135 current_received_ts = 181.462601 current_seq = 416 count = 3 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 181.463135 last_received_ts = 181.462601 last_seq = 416 current_ts = 181.479813 current_received_ts = 181.479263 current_seq = 417 count = 4 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 181.479813 last_received_ts = 181.479263 last_seq = 417 current_ts = 181.496475 current_received_ts = 181.495926 current_seq = 418 count = 5 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 181.496475 last_received_ts = 181.495926 last_seq = 418 current_ts = 181.513123 current_received_ts = 181.512604 current_seq = 419 count = 6 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 181.513123 last_received_ts = 181.512604 last_seq = 419 current_ts = 181.529816 current_received_ts = 181.529266 current_seq = 420 count = 7 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 181.529816 last_received_ts = 181.529266 last_seq = 420 current_ts = 181.546478 current_received_ts = 181.545944 current_seq = 421 count = 8 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 181.546478 last_received_ts = 181.545944 last_seq = 421 current_ts = 181.563141 current_received_ts = 181.562607 current_seq = 422 count = 9 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 181.563141 last_received_ts = 181.562607 last_seq = 422 current_ts = 181.579803 current_received_ts = 181.579269 current_seq = 423 count = 10 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 181.579803 last_received_ts = 181.579269 last_seq = 423 current_ts = 181.596481 current_received_ts = 181.595932 current_seq = 424 count = 11 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 181.596481 last_received_ts = 181.595932 last_seq = 424 current_ts = 181.613144 current_received_ts = 181.612610 current_seq = 425 count = 12 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 181.613144 last_received_ts = 181.612610 last_seq = 425 current_ts = 181.629807 current_received_ts = 181.629272 current_seq = 426 count = 13 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 181.629807 last_received_ts = 181.629272 last_seq = 426 current_ts = 181.646484 current_received_ts = 181.645935 current_seq = 427 count = 14 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 181.646484 last_received_ts = 181.645935 last_seq = 427 current_ts = 181.663147 current_received_ts = 181.662613 current_seq = 428 count = 15 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 181.663147 last_received_ts = 181.662613 last_seq = 428 current_ts = 181.679810 current_received_ts = 181.679504 current_seq = 429 count = 16 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 181.679810 last_received_ts = 181.679504 last_seq = 429 current_ts = 181.696472 current_received_ts = 181.696167 current_seq = 430 count = 17 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 181.696472 last_received_ts = 181.696167 last_seq = 430 current_ts = 181.713150 current_received_ts = 181.712845 current_seq = 431 count = 18 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 181.713150 last_received_ts = 181.712845 last_seq = 431 current_ts = 181.729813 current_received_ts = 181.729507 current_seq = 432 count = 19 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 181.729813 last_received_ts = 181.729507 last_seq = 432 current_ts = 181.746490 current_received_ts = 181.746170 current_seq = 433 count = 20 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 181.746490 last_received_ts = 181.746170 last_seq = 433 current_ts = 181.763153 current_received_ts = 181.762619 current_seq = 434 count = 21 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 181.763153 last_received_ts = 181.762619 last_seq = 434 current_ts = 181.779816 current_received_ts = 181.779282 current_seq = 435 count = 22 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 181.779816 last_received_ts = 181.779282 last_seq = 435 current_ts = 181.796494 current_received_ts = 181.795959 current_seq = 436 count = 23 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 181.796494 last_received_ts = 181.795959 last_seq = 436 current_ts = 181.813156 current_received_ts = 181.812622 current_seq = 437 count = 24 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 181.813156 last_received_ts = 181.812622 last_seq = 437 current_ts = 181.829819 current_received_ts = 181.829285 current_seq = 438 count = 25 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 181.829819 last_received_ts = 181.829285 last_seq = 438 current_ts = 181.846497 current_received_ts = 181.845963 current_seq = 439 count = 26 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 40us (kms_flip:4838) DEBUG: name = flip last_ts = 181.846497 last_received_ts = 181.845963 last_seq = 439 current_ts = 181.863159 current_received_ts = 181.862625 current_seq = 440 count = 27 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 181.863159 last_received_ts = 181.862625 last_seq = 440 current_ts = 181.879822 current_received_ts = 181.879288 current_seq = 441 count = 28 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 181.879822 last_received_ts = 181.879288 last_seq = 441 current_ts = 181.896500 current_received_ts = 181.895966 current_seq = 442 count = 29 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 40us (kms_flip:4838) DEBUG: name = flip last_ts = 181.896500 last_received_ts = 181.895966 last_seq = 442 current_ts = 181.913162 current_received_ts = 181.912628 current_seq = 443 count = 30 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 181.913162 last_received_ts = 181.912628 last_seq = 443 current_ts = 181.929825 current_received_ts = 181.929291 current_seq = 444 count = 31 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 181.929825 last_received_ts = 181.929291 last_seq = 444 current_ts = 181.946503 current_received_ts = 181.945953 current_seq = 445 count = 32 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 181.946503 last_received_ts = 181.945953 last_seq = 445 current_ts = 181.963165 current_received_ts = 181.962631 current_seq = 446 count = 33 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 181.963165 last_received_ts = 181.962631 last_seq = 446 current_ts = 181.979828 current_received_ts = 181.979523 current_seq = 447 count = 34 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 181.979828 last_received_ts = 181.979523 last_seq = 447 current_ts = 181.996506 current_received_ts = 181.996185 current_seq = 448 count = 35 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 181.996506 last_received_ts = 181.996185 last_seq = 448 current_ts = 182.013168 current_received_ts = 182.012848 current_seq = 449 count = 36 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 182.013168 last_received_ts = 182.012848 last_seq = 449 current_ts = 182.029831 current_received_ts = 182.029526 current_seq = 450 count = 37 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 182.029831 last_received_ts = 182.029526 last_seq = 450 current_ts = 182.046509 current_received_ts = 182.046188 current_seq = 451 count = 38 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 182.046509 last_received_ts = 182.046188 last_seq = 451 current_ts = 182.063171 current_received_ts = 182.062637 current_seq = 452 count = 39 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 182.063171 last_received_ts = 182.062637 last_seq = 452 current_ts = 182.079834 current_received_ts = 182.079300 current_seq = 453 count = 40 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 182.079834 last_received_ts = 182.079300 last_seq = 453 current_ts = 182.096512 current_received_ts = 182.095963 current_seq = 454 count = 41 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 39us (kms_flip:4838) DEBUG: name = flip last_ts = 182.096512 last_received_ts = 182.095963 last_seq = 454 current_ts = 182.113174 current_received_ts = 182.112717 current_seq = 455 count = 42 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 33us (kms_flip:4838) DEBUG: name = flip last_ts = 182.113174 last_received_ts = 182.112717 last_seq = 455 current_ts = 182.129837 current_received_ts = 182.129272 current_seq = 456 count = 43 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 32us (kms_flip:4838) DEBUG: name = flip last_ts = 182.129837 last_received_ts = 182.129272 last_seq = 456 current_ts = 182.146500 current_received_ts = 182.145905 current_seq = 457 count = 44 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 28us (kms_flip:4838) DEBUG: name = flip last_ts = 182.146500 last_received_ts = 182.145905 last_seq = 457 current_ts = 182.163162 current_received_ts = 182.162582 current_seq = 458 count = 45 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 182.163162 last_received_ts = 182.162582 last_seq = 458 current_ts = 182.179840 current_received_ts = 182.179245 current_seq = 459 count = 46 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 182.179840 last_received_ts = 182.179245 last_seq = 459 current_ts = 182.196503 current_received_ts = 182.195908 current_seq = 460 count = 47 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 182.196503 last_received_ts = 182.195908 last_seq = 460 current_ts = 182.213165 current_received_ts = 182.212570 current_seq = 461 count = 48 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 182.213165 last_received_ts = 182.212570 last_seq = 461 current_ts = 182.229843 current_received_ts = 182.229248 current_seq = 462 count = 49 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 182.229843 last_received_ts = 182.229248 last_seq = 462 current_ts = 182.246506 current_received_ts = 182.245911 current_seq = 463 count = 50 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 182.246506 last_received_ts = 182.245911 last_seq = 463 current_ts = 182.263168 current_received_ts = 182.262573 current_seq = 464 count = 51 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 182.263168 last_received_ts = 182.262573 last_seq = 464 current_ts = 182.279846 current_received_ts = 182.279465 current_seq = 465 count = 52 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 182.279846 last_received_ts = 182.279465 last_seq = 465 current_ts = 182.296509 current_received_ts = 182.296143 current_seq = 466 count = 53 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 182.296509 last_received_ts = 182.296143 last_seq = 466 current_ts = 182.313171 current_received_ts = 182.312805 current_seq = 467 count = 54 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 182.313171 last_received_ts = 182.312805 last_seq = 467 current_ts = 182.329849 current_received_ts = 182.329468 current_seq = 468 count = 55 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 182.329849 last_received_ts = 182.329468 last_seq = 468 current_ts = 182.346512 current_received_ts = 182.346146 current_seq = 469 count = 56 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 182.346512 last_received_ts = 182.346146 last_seq = 469 current_ts = 182.363174 current_received_ts = 182.362579 current_seq = 470 count = 57 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 182.363174 last_received_ts = 182.362579 last_seq = 470 current_ts = 182.379852 current_received_ts = 182.379257 current_seq = 471 count = 58 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 182.379852 last_received_ts = 182.379257 last_seq = 471 current_ts = 182.396515 current_received_ts = 182.395920 current_seq = 472 count = 59 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 182.396515 last_received_ts = 182.395920 last_seq = 472 current_ts = 182.413177 current_received_ts = 182.412582 current_seq = 473 count = 60 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 182.413177 last_received_ts = 182.412582 last_seq = 473 current_ts = 182.429855 current_received_ts = 182.429260 current_seq = 474 count = 61 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 182.429855 last_received_ts = 182.429260 last_seq = 474 current_ts = 182.446518 current_received_ts = 182.445923 current_seq = 475 count = 62 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 182.446518 last_received_ts = 182.445923 last_seq = 475 current_ts = 182.463181 current_received_ts = 182.462585 current_seq = 476 count = 63 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 182.463181 last_received_ts = 182.462585 last_seq = 476 current_ts = 182.479858 current_received_ts = 182.479263 current_seq = 477 count = 64 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 182.479858 last_received_ts = 182.479263 last_seq = 477 current_ts = 182.496521 current_received_ts = 182.495926 current_seq = 478 count = 65 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 182.496521 last_received_ts = 182.495926 last_seq = 478 current_ts = 182.513184 current_received_ts = 182.512589 current_seq = 479 count = 66 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 182.513184 last_received_ts = 182.512589 last_seq = 479 current_ts = 182.529861 current_received_ts = 182.529266 current_seq = 480 count = 67 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 182.529861 last_received_ts = 182.529266 last_seq = 480 current_ts = 182.546524 current_received_ts = 182.545929 current_seq = 481 count = 68 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 182.546524 last_received_ts = 182.545929 last_seq = 481 current_ts = 182.563187 current_received_ts = 182.562592 current_seq = 482 count = 69 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 182.563187 last_received_ts = 182.562592 last_seq = 482 current_ts = 182.579880 current_received_ts = 182.579483 current_seq = 483 count = 70 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 182.579880 last_received_ts = 182.579483 last_seq = 483 current_ts = 182.596527 current_received_ts = 182.596161 current_seq = 484 count = 71 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 182.596527 last_received_ts = 182.596161 last_seq = 484 current_ts = 182.613205 current_received_ts = 182.612823 current_seq = 485 count = 72 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 182.613205 last_received_ts = 182.612823 last_seq = 485 current_ts = 182.629868 current_received_ts = 182.629486 current_seq = 486 count = 73 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 182.629868 last_received_ts = 182.629486 last_seq = 486 current_ts = 182.646530 current_received_ts = 182.646164 current_seq = 487 count = 74 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 182.646530 last_received_ts = 182.646164 last_seq = 487 current_ts = 182.663193 current_received_ts = 182.662598 current_seq = 488 count = 75 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 182.663193 last_received_ts = 182.662598 last_seq = 488 current_ts = 182.679871 current_received_ts = 182.679276 current_seq = 489 count = 76 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 182.679871 last_received_ts = 182.679276 last_seq = 489 current_ts = 182.696533 current_received_ts = 182.695938 current_seq = 490 count = 77 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 182.696533 last_received_ts = 182.695938 last_seq = 490 current_ts = 182.713196 current_received_ts = 182.712601 current_seq = 491 count = 78 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 182.713196 last_received_ts = 182.712601 last_seq = 491 current_ts = 182.729874 current_received_ts = 182.729279 current_seq = 492 count = 79 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 182.729874 last_received_ts = 182.729279 last_seq = 492 current_ts = 182.746536 current_received_ts = 182.745941 current_seq = 493 count = 80 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 182.746536 last_received_ts = 182.745941 last_seq = 493 current_ts = 182.763199 current_received_ts = 182.762604 current_seq = 494 count = 81 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 28us (kms_flip:4838) DEBUG: name = flip last_ts = 182.763199 last_received_ts = 182.762604 last_seq = 494 current_ts = 182.779877 current_received_ts = 182.779282 current_seq = 495 count = 82 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 182.779877 last_received_ts = 182.779282 last_seq = 495 current_ts = 182.796539 current_received_ts = 182.795944 current_seq = 496 count = 83 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 182.796539 last_received_ts = 182.795944 last_seq = 496 current_ts = 182.813202 current_received_ts = 182.812607 current_seq = 497 count = 84 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 182.813202 last_received_ts = 182.812607 last_seq = 497 current_ts = 182.829880 current_received_ts = 182.829285 current_seq = 498 count = 85 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 182.829880 last_received_ts = 182.829285 last_seq = 498 current_ts = 182.846542 current_received_ts = 182.845947 current_seq = 499 count = 86 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 182.846542 last_received_ts = 182.845947 last_seq = 499 current_ts = 182.863205 current_received_ts = 182.862610 current_seq = 500 count = 87 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 182.863205 last_received_ts = 182.862610 last_seq = 500 current_ts = 182.879883 current_received_ts = 182.879501 current_seq = 501 count = 88 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 182.879883 last_received_ts = 182.879501 last_seq = 501 current_ts = 182.896545 current_received_ts = 182.896179 current_seq = 502 count = 89 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 182.896545 last_received_ts = 182.896179 last_seq = 502 current_ts = 182.913223 current_received_ts = 182.912842 current_seq = 503 count = 90 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 182.913223 last_received_ts = 182.912842 last_seq = 503 current_ts = 182.929886 current_received_ts = 182.929520 current_seq = 504 count = 91 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 182.929886 last_received_ts = 182.929520 last_seq = 504 current_ts = 182.946564 current_received_ts = 182.946167 current_seq = 505 count = 92 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 182.946564 last_received_ts = 182.946167 last_seq = 505 current_ts = 182.963211 current_received_ts = 182.962616 current_seq = 506 count = 93 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 182.963211 last_received_ts = 182.962616 last_seq = 506 current_ts = 182.979889 current_received_ts = 182.979294 current_seq = 507 count = 94 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 182.979889 last_received_ts = 182.979294 last_seq = 507 current_ts = 182.996552 current_received_ts = 182.995956 current_seq = 508 count = 95 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 182.996552 last_received_ts = 182.995956 last_seq = 508 current_ts = 183.013214 current_received_ts = 183.012619 current_seq = 509 count = 96 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 183.013214 last_received_ts = 183.012619 last_seq = 509 current_ts = 183.029892 current_received_ts = 183.029297 current_seq = 510 count = 97 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 183.029892 last_received_ts = 183.029297 last_seq = 510 current_ts = 183.046555 current_received_ts = 183.045959 current_seq = 511 count = 98 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 183.046555 last_received_ts = 183.045959 last_seq = 511 current_ts = 183.063217 current_received_ts = 183.062622 current_seq = 512 count = 99 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 183.063217 last_received_ts = 183.062622 last_seq = 512 current_ts = 183.079895 current_received_ts = 183.079300 current_seq = 513 count = 100 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 183.079895 last_received_ts = 183.079300 last_seq = 513 current_ts = 183.096558 current_received_ts = 183.095963 current_seq = 514 count = 101 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 183.096558 last_received_ts = 183.095963 last_seq = 514 current_ts = 183.113220 current_received_ts = 183.112625 current_seq = 515 count = 102 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 183.113220 last_received_ts = 183.112625 last_seq = 515 current_ts = 183.129898 current_received_ts = 183.129303 current_seq = 516 count = 103 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 183.129898 last_received_ts = 183.129303 last_seq = 516 current_ts = 183.146561 current_received_ts = 183.145966 current_seq = 517 count = 104 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 40us (kms_flip:4838) DEBUG: name = flip last_ts = 183.146561 last_received_ts = 183.145966 last_seq = 517 current_ts = 183.163223 current_received_ts = 183.162628 current_seq = 518 count = 105 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 183.163223 last_received_ts = 183.162628 last_seq = 518 current_ts = 183.179901 current_received_ts = 183.179520 current_seq = 519 count = 106 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 183.179901 last_received_ts = 183.179520 last_seq = 519 current_ts = 183.196564 current_received_ts = 183.196198 current_seq = 520 count = 107 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 183.196564 last_received_ts = 183.196198 last_seq = 520 current_ts = 183.213242 current_received_ts = 183.212860 current_seq = 521 count = 108 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 183.213242 last_received_ts = 183.212860 last_seq = 521 current_ts = 183.229904 current_received_ts = 183.229523 current_seq = 522 count = 109 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 183.229904 last_received_ts = 183.229523 last_seq = 522 current_ts = 183.246567 current_received_ts = 183.246201 current_seq = 523 count = 110 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 183.246567 last_received_ts = 183.246201 last_seq = 523 current_ts = 183.263229 current_received_ts = 183.262634 current_seq = 524 count = 111 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 27us (kms_flip:4838) DEBUG: name = flip last_ts = 183.263229 last_received_ts = 183.262634 last_seq = 524 current_ts = 183.279907 current_received_ts = 183.279312 current_seq = 525 count = 112 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 183.279907 last_received_ts = 183.279312 last_seq = 525 current_ts = 183.296570 current_received_ts = 183.295975 current_seq = 526 count = 113 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 42us (kms_flip:4838) DEBUG: name = flip last_ts = 183.296570 last_received_ts = 183.295975 last_seq = 526 current_ts = 183.313232 current_received_ts = 183.312637 current_seq = 527 count = 114 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 183.313232 last_received_ts = 183.312637 last_seq = 527 current_ts = 183.329910 current_received_ts = 183.329315 current_seq = 528 count = 115 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 26us (kms_flip:4838) DEBUG: name = flip last_ts = 183.329910 last_received_ts = 183.329315 last_seq = 528 current_ts = 183.346573 current_received_ts = 183.345978 current_seq = 529 count = 116 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 44us (kms_flip:4838) DEBUG: name = flip last_ts = 183.346573 last_received_ts = 183.345978 last_seq = 529 current_ts = 183.363235 current_received_ts = 183.362640 current_seq = 530 count = 117 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 183.363235 last_received_ts = 183.362640 last_seq = 530 current_ts = 183.379913 current_received_ts = 183.379318 current_seq = 531 count = 118 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 25us (kms_flip:4838) DEBUG: name = flip last_ts = 183.379913 last_received_ts = 183.379318 last_seq = 531 current_ts = 183.396576 current_received_ts = 183.395981 current_seq = 532 count = 119 seq_step = 1 (kms_flip:4838) DEBUG: Vblank took 1745us (kms_flip:4838) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931: (kms_flip:4838) CRITICAL: Failed assertion: end - start < 500 (kms_flip:4838) igt_core-INFO: Stack trace: (kms_flip:4838) igt_core-INFO: #0 ../lib/igt_core.c:2075 __igt_fail_assert() (kms_flip:4838) igt_core-INFO: #1 ../tests/kms_flip.c:926 run_test_on_crtc_set() (kms_flip:4838) igt_core-INFO: #2 ../tests/kms_flip.c:2029 run_pair() (kms_flip:4838) igt_core-INFO: #3 ../tests/kms_flip.c:2193 __igt_unique____real_main2105() (kms_flip:4838) igt_core-INFO: #4 ../tests/kms_flip.c:2105 main() (kms_flip:4838) igt_core-INFO: #5 [__libc_init_first+0x8a] (kms_flip:4838) igt_core-INFO: #6 [__libc_start_main+0x8b] (kms_flip:4838) igt_core-INFO: #7 [_start+0x25] **** END **** Dynamic subtest AC-DP2-HDMI-A3: FAIL (4.260s) |
| Dmesg |
<6> [181.352979] [IGT] kms_flip: starting dynamic subtest AC-DP2-HDMI-A3
<7> [181.354475] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:555]
<7> [181.355272] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:560]
<7> [181.418613] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [181.418812] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [181.419148] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [181.419429] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [181.419703] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [181.419952] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in dp_m_n (expected tu 64 data 5190451/8388608 link 288358/524288, found tu 0, data 0/0 link 0/0)
<7> [181.420219] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000080, found 0x00000000)
<7> [181.420451] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [181.420672] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [181.420890] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [181.421120] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [181.421333] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [181.421541] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [181.421744] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [181.421939] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [181.422162] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [181.422359] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [181.422544] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [181.422728] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [181.422920] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [181.423133] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [181.423315] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [181.423496] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [181.423671] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [181.423840] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [181.424009] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [181.424171] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [181.424333] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [181.424497] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [181.424645] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [181.424797] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [181.424947] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [181.425109] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [181.425254] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [181.425397] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [181.425537] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in enhanced_framing (expected yes, found no)
<7> [181.425676] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [181.425809] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [181.425951] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [181.426107] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 270000, found 0)
<7> [181.426245] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [181.426382] xe 0000:03:00.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:151:pipe A] releasing TC PLL 2
<7> [181.426537] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0xd (total dbuf slices 0xf), mbus joined? no->no
<7> [181.426649] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:151:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x5 -> 0x4
<7> [181.426780] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:145:cursor A] ddb (1989 - 2048) -> ( 0 - 0), size 59 -> 0
<7> [181.426901] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:151:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [181.427061] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:151:pipe A] enable: no [modeset]
<7> [181.427167] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:35:plane 1A] fb: [NOFB], visible: no
<7> [181.427267] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [181.427366] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [181.427464] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [181.427563] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [181.427665] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [181.428349] xe 0000:03:00.0: [drm:intel_audio_codec_disable [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G] Disable audio codec on [CRTC:151:pipe A]
<7> [181.449982] xe 0000:03:00.0: [drm:intel_audio_component_get_eld [xe]] Not valid for port E
<7> [181.450673] xe 0000:03:00.0: [drm:intel_disable_transcoder [xe]] disabling pipe A
<7> [181.468130] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2
<7> [181.470864] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disable TC PLL 2 (active 0x1, on? 1) for [CRTC:151:pipe A]
<7> [181.473275] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disabling TC PLL 2
<7> [181.473999] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [181.474482] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [181.474984] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [181.475444] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [181.475326] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [181.475887] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [181.476383] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [181.476919] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [181.477416] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [181.477863] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [181.478310] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [181.478707] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [181.479103] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [181.479455] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [181.479797] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [181.480144] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [181.480458] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [181.480765] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:527:DP-2]
<7> [181.482837] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [181.483187] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xd
<7> [181.483728] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_A
<7> [181.484089] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x1 (CLKGATE_DIS_DSSDSC=0x8a000000)
<7> [181.484476] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:151:pipe A]
<7> [181.485012] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe C]
<7> [181.485158] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.enable (expected yes, found no)
<7> [181.485388] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.active (expected yes, found no)
<7> [181.485601] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in cpu_transcoder (expected 2, found -1)
<7> [181.485795] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in lane_count (expected 4, found 0)
<7> [181.485993] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [181.486179] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [181.486364] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [181.486533] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [181.486693] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [181.486851] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [181.487018] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [181.487169] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [181.487319] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [181.487469] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [181.487606] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [181.487739] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [181.487868] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [181.488007] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [181.488132] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [181.488259] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [181.488387] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [181.488509] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [181.488626] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [181.488743] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [181.488859] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [181.488988] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [181.489102] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [181.489215] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [181.489325] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [181.489434] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [181.489536] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [181.489635] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [181.489735] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [181.489833] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [181.489931] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [181.490035] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [181.490131] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [181.490226] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [181.490321] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in port_clock (expected 222750, found 0)
<7> [181.490415] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [181.490510] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [181.490605] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in avi infoframe
<7> [181.490701] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [181.490795] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [181.490797] xe 0000:03:00.0: colorspace: RGB
<7> [181.490798] xe 0000:03:00.0: scan mode: Underscan
<7> [181.490800] xe 0000:03:00.0: colorimetry: No Data
<7> [181.490801] xe 0000:03:00.0: picture aspect: 16:9
<7> [181.490802] xe 0000:03:00.0: active aspect: Same as Picture
<7> [181.490803] xe 0000:03:00.0: itc: No Data
<7> [181.490805] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [181.490806] xe 0000:03:00.0: quantization range: Limited
<7> [181.490807] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [181.490808] xe 0000:03:00.0: video code: 16
<7> [181.490810] xe 0000:03:00.0: ycc quantization range: Limited
<7> [181.490811] xe 0000:03:00.0: hdmi content type: Graphics
<7> [181.490812] xe 0000:03:00.0: pixel repeat: 0
<7> [181.490813] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [181.490815] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [181.490910] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in spd infoframe
<7> [181.491013] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [181.491108] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [181.491109] xe 0000:03:00.0: vendor: Intel
<7> [181.491111] xe 0000:03:00.0: product: Discrete gfx
<7> [181.491112] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [181.491113] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [181.491207] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:269:pipe C] fastset requirement not met in hdmi infoframe
<7> [181.491303] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [181.491404] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [181.491405] xe 0000:03:00.0: empty frame
<7> [181.491406] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [181.491506] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:269:pipe C] fastset requirement not met, forcing full modeset
<7> [181.491604] xe 0000:03:00.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:269:pipe C] releasing TC PLL 3
<7> [181.491718] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xd -> 0x1 (total dbuf slices 0xf), mbus joined? no->no
<7> [181.491803] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:269:pipe C] dbuf slices 0xc -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x4 -> 0x0
<7> [181.491904] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:263:cursor C] ddb (1989 - 2048) -> ( 0 - 0), size 59 -> 0
<7> [181.492010] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:269:pipe C] min cdclk: 192000 kHz -> 0 kHz
<7> [181.492140] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:269:pipe C] enable: no [modeset]
<7> [181.492244] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:153:plane 1C] fb: [NOFB], visible: no
<7> [181.492342] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:183:plane 2C] fb: [NOFB], visible: no
<7> [181.492441] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:213:plane 3C] fb: [NOFB], visible: no
<7> [181.492537] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:243:plane 4C] fb: [NOFB], visible: no
<7> [181.492634] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:253:plane 5C] fb: [NOFB], visible: no
<7> [181.492728] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:263:cursor C] fb: [NOFB], visible: no
<7> [181.493132] xe 0000:03:00.0: [drm:intel_audio_codec_disable [xe]] [CONNECTOR:539:HDMI-A-3][ENCODER:538:DDI TC3/PHY H] Disable audio codec on [CRTC:269:pipe C]
<7> [181.526439] xe 0000:03:00.0: [drm:intel_audio_component_get_eld [xe]] Not valid for port F
<7> [181.527903] xe 0000:03:00.0: [drm:intel_disable_transcoder [xe]] disabling pipe C
<7> [181.539664] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [181.544020] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disable TC PLL 3 (active 0x4, on? 1) for [CRTC:269:pipe C]
<7> [181.546640] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disabling TC PLL 3
<7> [181.547548] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [181.548113] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [181.548617] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [181.549127] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [181.549563] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [181.550003] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [181.550430] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [181.550832] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [181.551265] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [181.551622] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [181.551992] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [181.552552] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [181.552871] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [181.553183] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [181.553473] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [181.553770] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [181.554068] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:539:HDMI-A-3]
<7> [181.554369] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [181.554723] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [181.555310] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_C
<7> [181.555791] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x4 (CLKGATE_DIS_DSSDSC=0xaa000000)
<7> [181.556281] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [181.556757] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:269:pipe C]
<7> [181.557361] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe B]
<7> [181.558121] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:505:pipe D]
<7> [181.558452] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe B]
<7> [181.558484] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:539:HDMI-A-3]
<7> [181.558572] xe 0000:03:00.0: [drm:intel_hdmi_compute_output_format [xe]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [181.558752] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:387:pipe B] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [181.558928] xe 0000:03:00.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:538:DDI TC3/PHY H] [CRTC:387:pipe B]
<7> [181.559115] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.enable (expected no, found yes)
<7> [181.559285] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.active (expected no, found yes)
<7> [181.559448] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in cpu_transcoder (expected -1, found 1)
<7> [181.559596] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in lane_count (expected 0, found 4)
<7> [181.559740] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [181.559882] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [181.560027] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [181.560164] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [181.560301] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [181.560432] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [181.560558] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [181.560682] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [181.560805] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [181.560925] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [181.561054] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [181.561174] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [181.561291] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [181.561407] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [181.561524] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [181.561630] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [181.561737] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [181.561841] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [181.561943] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [181.562055] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [181.562156] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [181.562258] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [181.562359] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [181.562460] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [181.562561] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [181.562662] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [181.562763] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [181.562863] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [181.562971] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [181.563078] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [181.563184] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [181.563290] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in dpll_hw_state
<7> [181.563394] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [181.563496] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] cx0pll_hw_state: lane_count: 0, ssc_enabled: no, use_c10: no, tbt_mode: no
<7> [181.563599] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] c20pll_hw_state:
<7> [181.563700] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] tx[0] = 0x0000, tx[1] = 0x0000, tx[2] = 0x0000
<7> [181.563802] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] cmn[0] = 0x0000, cmn[1] = 0x0000, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [181.563903] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[0] = 0x0000
<7> [181.564012] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[1] = 0x0000
<7> [181.564113] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[2] = 0x0000
<7> [181.564215] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[3] = 0x0000
<7> [181.564317] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[4] = 0x0000
<7> [181.564421] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[5] = 0x0000
<7> [181.564521] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[6] = 0x0000
<7> [181.564623] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[7] = 0x0000
<7> [181.564724] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[8] = 0x0000
<7> [181.564825] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mplla[9] = 0x0000
<7> [181.564926] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[10] = 0x0000
<7> [181.565034] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] vdr: custom width: 0x00, serdes rate: 0x00, hdmi rate: 0x00
<7> [181.565132] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [181.565234] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: no, tbt_mode: no
<7> [181.565334] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] c20pll_hw_state:
<7> [181.565435] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] tx[0] = 0xbe88, tx[1] = 0x5800, tx[2] = 0x0000
<7> [181.565535] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [181.565636] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[0] = 0x40e8
<7> [181.565737] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[1] = 0x7d20
<7> [181.565838] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[2] = 0x0a06
<7> [181.565939] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[3] = 0x8f1c
<7> [181.566050] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[4] = 0x0000
<7> [181.566151] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[5] = 0x0000
<7> [181.566252] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[6] = 0x2200
<7> [181.566354] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[7] = 0xffff
<7> [181.566455] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[8] = 0x0400
<7> [181.566556] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[9] = 0x0000
<7> [181.566656] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] mpllb[10] = 0x0001
<7> [181.566756] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] vdr: custom width: 0x00, serdes rate: 0x00, hdmi rate: 0x00
<7> [181.566862] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [181.566975] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [181.567085] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [181.567188] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in port_clock (expected 0, found 222750)
<7> [181.567292] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [181.567395] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006)
<7> [181.567497] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in avi infoframe
<7> [181.567597] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [181.567697] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [181.567797] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [181.567799] xe 0000:03:00.0: colorspace: RGB
<7> [181.567800] xe 0000:03:00.0: scan mode: Underscan
<7> [181.567801] xe 0000:03:00.0: colorimetry: No Data
<7> [181.567803] xe 0000:03:00.0: picture aspect: 16:9
<7> [181.567804] xe 0000:03:00.0: active aspect: Same as Picture
<7> [181.567805] xe 0000:03:00.0: itc: No Data
<7> [181.567806] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [181.567808] xe 0000:03:00.0: quantization range: Limited
<7> [181.567809] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [181.567810] xe 0000:03:00.0: video code: 16
<7> [181.567811] xe 0000:03:00.0: ycc quantization range: Limited
<7> [181.567813] xe 0000:03:00.0: hdmi content type: Graphics
<7> [181.567814] xe 0000:03:00.0: pixel repeat: 0
<7> [181.567815] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [181.567817] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in spd infoframe
<7> [181.567918] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [181.568026] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [181.568127] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [181.568129] xe 0000:03:00.0: vendor: Intel
<7> [181.568130] xe 0000:03:00.0: product: Discrete gfx
<7> [181.568132] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [181.568133] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:387:pipe B] fastset requirement not met in hdmi infoframe
<7> [181.568235] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] expected:
<7> [181.568336] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] found:
<7> [181.568437] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [181.568439] xe 0000:03:00.0: empty frame
<7> [181.568440] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:387:pipe B] fastset requirement not met, forcing full modeset
<7> [181.568571] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [181.568663] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:387:pipe B] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x0 -> 0x2
<7> [181.568773] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:271:plane 1B] ddb ( 0 - 0) -> ( 0 - 4037), size 0 -> 4037
<7> [181.568860] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:381:cursor B] ddb ( 0 - 0) -> (4037 - 4096), size 0 -> 59
<7> [181.568947] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:271:plane 1B] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [181.569066] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:271:plane 1B] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 4, 9, 9, 17, 17, 17, 17, 0, 15, 0
<7> [181.569154] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:271:plane 1B] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 62, 62, 139, 139, 261, 261, 261, 261, 137, 230, 244
<7> [181.569239] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:271:plane 1B] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 123, 123, 245, 245, 367, 367, 0, 0, 138, 306, 306
<7> [181.569334] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:387:pipe B] data rate 594000 num active planes 1
<7> [181.569444] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 624 qgv_peak_bw: 48000
<7> [181.569554] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 624 qgv_peak_bw: 48000
<7> [181.569661] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 624
<7> [181.569768] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:387:pipe B] min cdclk: 0 kHz -> 192000 kHz
<7> [181.569888] xe 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 18563 kHz -> 9282 kHz
<7> [181.570019] xe 0000:03:00.0: [drm:intel_find_dpll [xe]] [CRTC:387:pipe B] allocated TC PLL 3
<7> [181.570127] xe 0000:03:00.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:387:pipe B] reserving TC PLL 3
<7> [181.570233] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:387:pipe B] enable: yes [modeset]
<7> [181.570342] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [181.570447] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: B, pipe bpp: 36, dithering: 0
<7> [181.570554] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [181.570658] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [181.570762] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [181.570865] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [181.570973] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [181.571076] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] GCP: 0x6
<7> [181.571179] xe 0000:03:00.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [181.571181] xe 0000:03:00.0: colorspace: RGB
<7> [181.571182] xe 0000:03:00.0: scan mode: Underscan
<7> [181.571184] xe 0000:03:00.0: colorimetry: No Data
<7> [181.571185] xe 0000:03:00.0: picture aspect: 16:9
<7> [181.571186] xe 0000:03:00.0: active aspect: Same as Picture
<7> [181.571187] xe 0000:03:00.0: itc: No Data
<7> [181.571189] xe 0000:03:00.0: extended colorimetry: xvYCC 601
<7> [181.571190] xe 0000:03:00.0: quantization range: Limited
<7> [181.571191] xe 0000:03:00.0: nups: Unknown Non-uniform Scaling
<7> [181.571192] xe 0000:03:00.0: video code: 16
<7> [181.571193] xe 0000:03:00.0: ycc quantization range: Limited
<7> [181.571195] xe 0000:03:00.0: hdmi content type: Graphics
<7> [181.571196] xe 0000:03:00.0: pixel repeat: 0
<7> [181.571197] xe 0000:03:00.0: bar top 0, bottom 0, left 0, right 0
<7> [181.571199] xe 0000:03:00.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [181.571200] xe 0000:03:00.0: vendor: Intel
<7> [181.571201] xe 0000:03:00.0: product: Discrete gfx
<7> [181.571203] xe 0000:03:00.0: source device information: PC General (0x9)
<7> [181.571204] xe 0000:03:00.0: HDMI infoframe: Vendor, version 1, length 4
<7> [181.571206] xe 0000:03:00.0: empty frame
<7> [181.571207] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 10 00 08 00 65 32 00 01 00 00 00 00 00 00 00 00
<7> [181.571309] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 61 a4 9a 00 4d 69 20 54 56 09 07 07 11 17 50 51
<7> [181.571412] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 07 00 00 00
<7> [181.571521] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [181.571625] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [181.571729] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 0, guardband: 45 vsync start: 41, vsync end: 36
<7> [181.571832] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1080, vmax vblank: 1080, vmin vtotal: 1125, vmax vtotal: 1125
<7> [181.571934] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [181.572045] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [181.572146] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [181.572249] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [181.572351] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [181.572452] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [181.572555] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port clock: 222750, pixel rate 148500, min cdclk 192000, min voltage level 0
<7> [181.572660] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0
<7> [181.572764] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [181.572867] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe src: 1920x1080+0+0
<7> [181.572975] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [181.573076] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [181.573178] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [181.573280] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: no, tbt_mode: no
<7> [181.573380] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] c20pll_hw_state:
<7> [181.573480] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] tx[0] = 0xbe88, tx[1] = 0x5800, tx[2] = 0x0000
<7> [181.573581] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [181.573682] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[0] = 0x40e8
<7> [181.573783] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[1] = 0x7d20
<7> [181.573884] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[2] = 0x0a06
<7> [181.573990] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[3] = 0x8f1c
<7> [181.574090] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[4] = 0x0000
<7> [181.574189] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[5] = 0x0000
<7> [181.574289] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[6] = 0x2200
<7> [181.574389] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[7] = 0xffff
<7> [181.574491] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[8] = 0x0400
<7> [181.574595] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[9] = 0x0000
<7> [181.574697] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[10] = 0x0001
<7> [181.574799] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vdr: custom width: 0x00, serdes rate: 0x00, hdmi rate: 0x00
<7> [181.574901] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x40000000 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [181.575009] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [181.575110] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [181.575210] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0db0 0x0000 0x0000
<7> [181.575311] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0db0 0x0000
<7> [181.575411] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0db0
<7> [181.575511] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0100 0x0100 0x0100
<7> [181.575611] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [181.575715] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [181.575819] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [181.575922] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [181.576030] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [181.576130] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:271:plane 1B] fb: [FB:555] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [181.576232] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [181.576331] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [181.576432] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:301:plane 2B] fb: [NOFB], visible: no
<7> [181.576532] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:331:plane 3B] fb: [NOFB], visible: no
<7> [181.576633] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:361:plane 4B] fb: [NOFB], visible: no
<7> [181.576738] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:371:plane 5B] fb: [NOFB], visible: no
<7> [181.576841] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:381:cursor B] fb: [NOFB], visible: no
<7> [181.577471] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [181.577592] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_B
<7> [181.577931] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x2 (CLKGATE_DIS_DSSDSC=0xa2000000)
<7> [181.578269] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [181.578370] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [181.578468] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [181.578562] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [181.578655] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [181.578748] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [181.578841] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [181.578932] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [181.578964] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2
<7> [181.579035] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [181.579129] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [181.579224] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [181.579317] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [181.579410] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [181.579501] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [181.579592] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [181.579683] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [181.579988] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf
<7> [181.580162] xe 0000:03:00.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: *)
<7> [181.580452] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: yes)
<7> [181.580568] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enable TC PLL 3 (active 0x2, on? 0) for [CRTC:387:pipe B]
<7> [181.580673] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enabling TC PLL 3
<7> [181.583260] xe 0000:03:00.0: [drm:intel_enable_transcoder [xe]] enabling pipe B
<7> [181.602081] xe 0000:03:00.0: [drm:intel_audio_codec_enable [xe]] [CONNECTOR:539:HDMI-A-3][ENCODER:538:DDI TC3/PHY H] Enable audio codec on [CRTC:387:pipe B], 36 bytes ELD
<7> [181.618255] xe 0000:03:00.0: [drm:audio_config_hdmi_pixel_clock [xe]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [181.618501] xe 0000:03:00.0: [drm:hsw_audio_config_update [xe]] using automatic N
<7> [181.618881] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:539:HDMI-A-3]
<7> [181.619362] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:387:pipe B]
<7> [181.623659] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [181.623709] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:527:DP-2]
<7> [181.623862] xe 0000:03:00.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:526:DDI TC2/PHY G][CRTC:151:pipe A] DP link limits: pixel clock 148500 kHz DSC off max lanes 4 max rate 540000 max pipe_bpp 36 min link_bpp 18.0000 max link_bpp 36.0000
<7> [181.624151] xe 0000:03:00.0: [drm:intel_dp_compute_output_format [xe]] DP lane count 4 clock 270000 bpp input 36 compressed 0.0000 HDR no link rate required 668250 available 1080000
<7> [181.624404] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [181.624645] xe 0000:03:00.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:526:DDI TC2/PHY G] [CRTC:151:pipe A]
<7> [181.624873] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [181.625116] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [181.625334] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [181.625544] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [181.625748] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 5190451/8388608 link 288358/524288)
<7> [181.625942] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000080)
<7> [181.626163] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [181.626356] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [181.626550] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [181.626739] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [181.626922] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [181.627121] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [181.627296] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [181.627472] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [181.627639] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [181.627797] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [181.627967] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [181.628120] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [181.628273] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [181.628426] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [181.628575] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [181.628722] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [181.628867] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [181.629016] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [181.629154] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [181.629290] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [181.629427] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [181.629560] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [181.629690] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [181.629816] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [181.629938] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [181.630081] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [181.630201] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [181.630320] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [181.630293] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [181.630440] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in enhanced_framing (expected no, found yes)
<6> [181.630614] pcieport 0000:00:01.0: PME: Spurious native interrupt!
<7> [181.630561] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [181.630673] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [181.630784] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [181.630893] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 0, found 270000)
<7> [181.631019] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [181.631162] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0xf (total dbuf slices 0xf), mbus joined? yes->no
<7> [181.631258] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:151:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x2 -> 0x3
<7> [181.631357] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:387:pipe B] dbuf slices 0xf -> 0xc, ddb (0 - 4096) -> (0 - 2048), active pipes 0x2 -> 0x3
<7> [181.631477] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 1989), size 0 -> 1989
<7> [181.631566] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:145:cursor A] ddb ( 0 - 0) -> (1989 - 2048), size 0 -> 59
<7> [181.631649] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [181.631733] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 4, 9, 9, 17, 17, 17, 17, 0, 15, 0
<7> [181.631815] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 62, 62, 139, 139, 261, 261, 261, 261, 137, 230, 244
<7> [181.631897] xe 0000:03:00.0: [drm:skl_print_plane_wm_changes [xe]] [PLANE:35:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 123, 123, 245, 245, 367, 367, 0, 0, 138, 306, 306
<7> [181.631992] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:271:plane 1B] ddb ( 0 - 4037) -> ( 0 - 1989), size 4037 -> 1989
<7> [181.632068] xe 0000:03:00.0: [drm:skl_print_plane_ddb_changes [xe]] [PLANE:381:cursor B] ddb (4037 - 4096) -> (1989 - 2048), size 59 -> 59
<7> [181.632154] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:151:pipe A] data rate 594000 num active planes 1
<7> [181.632257] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 1248 qgv_peak_bw: 48000
<7> [181.632358] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 1248 qgv_peak_bw: 48000
<7> [181.632457] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 1248
<7> [181.632560] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:151:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [181.632675] xe 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 9282 kHz -> 18563 kHz
<7> [181.632786] xe 0000:03:00.0: [drm:intel_find_dpll [xe]] [CRTC:151:pipe A] allocated TC PLL 2
<7> [181.632885] xe 0000:03:00.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:151:pipe A] reserving TC PLL 2
<7> [181.632994] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:151:pipe A] enable: yes [modeset]
<7> [181.633098] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: DP (0x80), output format: RGB, sink format: RGB
<7> [181.633199] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: A, pipe bpp: 36, dithering: 0
<7> [181.633297] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [181.633394] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [181.633489] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [181.633586] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [181.633685] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] dp m_n: lanes: 4; data_m: 5190451, data_n: 8388608, link_m: 288358, link_n: 524288, tu: 64
<7> [181.633782] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] dp m2_n2: lanes: 4; data_m: 0, data_n: 0, link_m: 0, link_n: 0, tu: 0
<7> [181.633877] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: enabled
<7> [181.634005] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [181.634106] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] psr: disabled, selective update: disabled, panel replay: disabled, selective fetch: disabled
<7> [181.634204] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] minimum hblank: 0
<7> [181.634300] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 0, infoframes enabled: 0x0
<7> [181.634395] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 10 00 09 00 68 36 00 01 00 00 00 00 00 00 00 00
<7> [181.634516] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 61 a4 9a 00 44 50 46 39 30 34 33 35 09 07 07 11
<7> [181.634631] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 17 50 51 07 00 00 00 00
<7> [181.634725] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1
<7> [181.634820] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [181.634913] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 0, guardband: 42 vsync start: 41, vsync end: 36
<7> [181.635019] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1083, vmax vblank: 1083, vmin vtotal: 1125, vmax vtotal: 1125
<7> [181.635113] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [181.635206] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [181.635301] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [181.635396] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [181.635492] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [181.635586] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [181.635681] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port clock: 270000, pixel rate 148500, min cdclk 192000, min voltage level 0
<7> [181.635776] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0
<7> [181.635869] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [181.635968] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe src: 1920x1080+0+0
<7> [181.636066] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [181.636164] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [181.636261] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [181.636356] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: no, tbt_mode: no
<7> [181.636452] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] c20pll_hw_state:
<7> [181.636544] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] tx[0] = 0xbe88, tx[1] = 0x4800, tx[2] = 0x0000
<7> [181.636639] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000
<7> [181.636732] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[0] = 0x308c
<7> [181.636825] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[1] = 0x2110
<7> [181.636920] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[2] = 0xcc9c
<7> [181.637023] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[3] = 0xbfc1
<7> [181.637117] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[4] = 0x4b9a
<7> [181.637210] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[5] = 0x3f81
<7> [181.637303] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[6] = 0x2000
<7> [181.637393] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[7] = 0x0001
<7> [181.637485] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[8] = 0x5000
<7> [181.637578] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[9] = 0x0000
<7> [181.637671] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[10] = 0x0000
<7> [181.637763] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vdr: custom width: 0x00, serdes rate: 0x42, hdmi rate: 0x00
<7> [181.637859] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x40000000 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [181.637964] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [181.638060] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [181.638156] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0db0 0x0000 0x0000
<7> [181.638254] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0db0 0x0000
<7> [181.638352] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0db0
<7> [181.638448] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0100 0x0100 0x0100
<7> [181.638542] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [181.638636] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [181.638727] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [181.638821] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [181.638915] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [181.639021] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:35:plane 1A] fb: [FB:555] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [181.639123] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [181.639221] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [181.639326] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [181.639425] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [181.639522] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [181.639618] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [181.639713] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [181.640571] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_A
<7> [181.640699] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x1 (CLKGATE_DIS_DSSDSC=0xa0000000)
<7> [181.640812] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:507:DDI TC1/PHY F]
<7> [181.640907] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST A]
<7> [181.641009] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST B]
<7> [181.641100] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST C]
<7> [181.641190] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:512:DP-MST D]
<7> [181.641280] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:526:DDI TC2/PHY G]
<7> [181.641372] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST A]
<7> [181.641462] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST B]
<7> [181.641551] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST C]
<7> [181.641639] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:531:DP-MST D]
<7> [181.641726] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:538:DDI TC3/PHY H]
<7> [181.641812] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:542:DDI TC4/PHY I]
<7> [181.641902] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST A]
<7> [181.641997] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST B]
<7> [181.642084] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST C]
<7> [181.642170] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:547:DP-MST D]
<7> [181.651612] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [181.651945] xe 0000:03:00.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: B)
<7> [181.668537] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enable TC PLL 2 (active 0x1, on? 0) for [CRTC:151:pipe A]
<7> [181.668660] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enabling TC PLL 2
<7> [181.671172] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2
<7> [181.675715] xe 0000:03:00.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX USBC2/DDI TC2/PHY G: DPCD: 12 14 c4 01 01 15 01 81 00 01 04 01 0f 00 01
<7> [181.682999] xe 0000:03:00.0: [drm:intel_dp_init_lttpr_and_dprx_caps [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] LTTPR common capabilities: 00 00 00 00 00 00 00 00
<7> [181.684374] xe 0000:03:00.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX USBC2/DDI TC2/PHY G: DPCD: 12 14 c4 01 01 15 01 81 00 01 04 01 0f 00 01
<7> [181.685041] xe 0000:03:00.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] Using LINK_BW_SET value 0a
<7> [181.686899] xe 0000:03:00.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [181.687787] xe 0000:03:00.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] Using DP training pattern TPS1
<7> [181.694025] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] Clock recovery OK
<7> [181.694585] xe 0000:03:00.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] Using DP training pattern TPS3
<7> [181.701904] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] Channel EQ done. DP Training successful
<7> [181.702525] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G][DPRX] Link Training passed at link rate = 270000, lane count = 4
<7> [181.704137] xe 0000:03:00.0: [drm:intel_enable_transcoder [xe]] enabling pipe A
<7> [181.722067] xe 0000:03:00.0: [drm:intel_audio_codec_enable [xe]] [CONNECTOR:527:DP-2][ENCODER:526:DDI TC2/PHY G] Enable audio codec on [CRTC:151:pipe A], 40 bytes ELD
<7> [181.736731] xe 0000:03:00.0: [drm:intel_audio_component_get_power [xe]] restored AUD_FREQ_CNTRL to 0x8010
<7> [181.738795] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:527:DP-2]
<7> [181.739704] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:151:pipe A]
<7> [181.811069] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2
<7> [183.755035] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2
<7> [183.774126] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2
<6> [185.613945] [IGT] kms_flip: finished subtest AC-DP2-HDMI-A3, FAIL
|