igt@kms_flip@flip-vs-expired-vblank-interruptible@c-dp2 on shard-bmg@IGT

Detail	Value
Duration	1.27 seconds
Hostname	shard-bmg-9
Igt-Version	IGT-Version: 2.3-g46cb55353 (x86_64) (Linux: 7.0.0-rc1-lgci-xe-xe-4617-3b1923ab37ecd72e1-debug+ x86_64)
Out	Starting dynamic subtest: C-DP2 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:921 run_test_on_crtc_set.constprop.0() #2 ../tests/kms_flip.c:1887 run_test() #3 ../tests/kms_flip.c:2156 __igt_unique____real_main2043() #4 ../tests/kms_flip.c:2043 main() #5 [__libc_init_first+0x8a] #6 [__libc_start_main+0x8b] #7 [_start+0x25] Dynamic subtest C-DP2: FAIL (1.266s)
Err	Starting dynamic subtest: C-DP2 (kms_flip:17616) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:926: (kms_flip:17616) CRITICAL: Failed assertion: end - start < 500 (kms_flip:17616) CRITICAL: Last errno: 4, Interrupted system call Dynamic subtest C-DP2 failed. ** DEBUG (kms_flip:17616) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x100000000000009, size=0) (kms_flip:17616) drmtest-DEBUG: Test requirement passed: is_intel_device(fd) (kms_flip:17616) drmtest-DEBUG: Test requirement passed: is_intel_device(fd) (kms_flip:17616) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=1, pitch=7680) (kms_flip:17616) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd) (kms_flip:17616) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x100000000000009, size=0) (kms_flip:17616) drmtest-DEBUG: Test requirement passed: is_intel_device(fd) (kms_flip:17616) drmtest-DEBUG: Test requirement passed: is_intel_device(fd) (kms_flip:17616) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=7680) (kms_flip:17616) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd) (kms_flip:17616) intel_allocator_simple-DEBUG: Using simple allocator (kms_flip:17616) intel_allocator_simple-DEBUG: <ial: 0x56102f92d6f0, fd: 5> objects: 3, reserved_areas: 0 (kms_flip:17616) intel_allocator_simple-DEBUG: <ial: 0x56102f92d6f0, fd: 5> objects: 3, reserved_areas: 0 (kms_flip:17616) intel_allocator_simple-DEBUG: Using simple allocator (kms_flip:17616) intel_allocator_simple-DEBUG: <ial: 0x56102f92aea0, fd: 5> objects: 3, reserved_areas: 0 (kms_flip:17616) intel_allocator_simple-DEBUG: <ial: 0x56102f92aea0, fd: 5> objects: 3, reserved_areas: 0 (kms_flip:17616) intel_allocator_simple-DEBUG: Using simple allocator (kms_flip:17616) intel_allocator_simple-DEBUG: <ial: 0x56102f92d6f0, fd: 5> objects: 3, reserved_areas: 0 (kms_flip:17616) intel_allocator_simple-DEBUG: <ial: 0x56102f92d6f0, fd: 5> objects: 3, reserved_areas: 0 (kms_flip:17616) intel_allocator_simple-DEBUG: Using simple allocator (kms_flip:17616) intel_allocator_simple-DEBUG: <ial: 0x56102f92aea0, fd: 5> objects: 3, reserved_areas: 0 (kms_flip:17616) intel_allocator_simple-DEBUG: <ial: 0x56102f92aea0, fd: 5> objects: 3, reserved_areas: 0 (kms_flip:17616) igt_kms-INFO: 1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 (kms_flip:17616) DEBUG: No stale events found (kms_flip:17616) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0 (kms_flip:17616) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0 (kms_flip:17616) DEBUG: name = flip last_ts = 0.000000 last_received_ts = 0.000000 last_seq = 0 current_ts = 453.346008 current_received_ts = 453.345642 current_seq = 93 count = 0 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 39us (kms_flip:17616) DEBUG: name = flip last_ts = 453.346008 last_received_ts = 453.345642 last_seq = 93 current_ts = 453.362671 current_received_ts = 453.362305 current_seq = 94 count = 1 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 28us (kms_flip:17616) DEBUG: name = flip last_ts = 453.362671 last_received_ts = 453.362305 last_seq = 94 current_ts = 453.379333 current_received_ts = 453.378967 current_seq = 95 count = 2 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 39us (kms_flip:17616) DEBUG: name = flip last_ts = 453.379333 last_received_ts = 453.378967 last_seq = 95 current_ts = 453.396027 current_received_ts = 453.395630 current_seq = 96 count = 3 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 27us (kms_flip:17616) DEBUG: name = flip last_ts = 453.396027 last_received_ts = 453.395630 last_seq = 96 current_ts = 453.412689 current_received_ts = 453.412231 current_seq = 97 count = 4 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 26us (kms_flip:17616) DEBUG: name = flip last_ts = 453.412689 last_received_ts = 453.412231 last_seq = 97 current_ts = 453.429352 current_received_ts = 453.428986 current_seq = 98 count = 5 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 39us (kms_flip:17616) DEBUG: name = flip last_ts = 453.429352 last_received_ts = 453.428986 last_seq = 98 current_ts = 453.446014 current_received_ts = 453.445587 current_seq = 99 count = 6 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 25us (kms_flip:17616) DEBUG: name = flip last_ts = 453.446014 last_received_ts = 453.445587 last_seq = 99 current_ts = 453.462708 current_received_ts = 453.462311 current_seq = 100 count = 7 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 25us (kms_flip:17616) DEBUG: name = flip last_ts = 453.462708 last_received_ts = 453.462311 last_seq = 100 current_ts = 453.479370 current_received_ts = 453.479004 current_seq = 101 count = 8 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 37us (kms_flip:17616) DEBUG: name = flip last_ts = 453.479370 last_received_ts = 453.479004 last_seq = 101 current_ts = 453.496033 current_received_ts = 453.495575 current_seq = 102 count = 9 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 26us (kms_flip:17616) DEBUG: name = flip last_ts = 453.496033 last_received_ts = 453.495575 last_seq = 102 current_ts = 453.512695 current_received_ts = 453.512207 current_seq = 103 count = 10 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 26us (kms_flip:17616) DEBUG: name = flip last_ts = 453.512695 last_received_ts = 453.512207 last_seq = 103 current_ts = 453.529358 current_received_ts = 453.528992 current_seq = 104 count = 11 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 24us (kms_flip:17616) DEBUG: name = flip last_ts = 453.529358 last_received_ts = 453.528992 last_seq = 104 current_ts = 453.546051 current_received_ts = 453.545593 current_seq = 105 count = 12 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 25us (kms_flip:17616) DEBUG: name = flip last_ts = 453.546051 last_received_ts = 453.545593 last_seq = 105 current_ts = 453.562714 current_received_ts = 453.562347 current_seq = 106 count = 13 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 25us (kms_flip:17616) DEBUG: name = flip last_ts = 453.562714 last_received_ts = 453.562347 last_seq = 106 current_ts = 453.579376 current_received_ts = 453.579010 current_seq = 107 count = 14 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 24us (kms_flip:17616) DEBUG: name = flip last_ts = 453.579376 last_received_ts = 453.579010 last_seq = 107 current_ts = 453.596039 current_received_ts = 453.595612 current_seq = 108 count = 15 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 25us (kms_flip:17616) DEBUG: name = flip last_ts = 453.596039 last_received_ts = 453.595612 last_seq = 108 current_ts = 453.612701 current_received_ts = 453.612274 current_seq = 109 count = 16 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 26us (kms_flip:17616) DEBUG: name = flip last_ts = 453.612701 last_received_ts = 453.612274 last_seq = 109 current_ts = 453.629395 current_received_ts = 453.628906 current_seq = 110 count = 17 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 25us (kms_flip:17616) DEBUG: name = flip last_ts = 453.629395 last_received_ts = 453.628906 last_seq = 110 current_ts = 453.646057 current_received_ts = 453.645660 current_seq = 111 count = 18 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 26us (kms_flip:17616) DEBUG: name = flip last_ts = 453.646057 last_received_ts = 453.645660 last_seq = 111 current_ts = 453.662720 current_received_ts = 453.662262 current_seq = 112 count = 19 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 26us (kms_flip:17616) DEBUG: name = flip last_ts = 453.662720 last_received_ts = 453.662262 last_seq = 112 current_ts = 453.679382 current_received_ts = 453.678894 current_seq = 113 count = 20 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 25us (kms_flip:17616) DEBUG: name = flip last_ts = 453.679382 last_received_ts = 453.678894 last_seq = 113 current_ts = 453.696045 current_received_ts = 453.695679 current_seq = 114 count = 21 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 26us (kms_flip:17616) DEBUG: name = flip last_ts = 453.696045 last_received_ts = 453.695679 last_seq = 114 current_ts = 453.712738 current_received_ts = 453.712250 current_seq = 115 count = 22 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 26us (kms_flip:17616) DEBUG: name = flip last_ts = 453.712738 last_received_ts = 453.712250 last_seq = 115 current_ts = 453.729401 current_received_ts = 453.728912 current_seq = 116 count = 23 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 25us (kms_flip:17616) DEBUG: name = flip last_ts = 453.729401 last_received_ts = 453.728912 last_seq = 116 current_ts = 453.746063 current_received_ts = 453.745697 current_seq = 117 count = 24 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 26us (kms_flip:17616) DEBUG: name = flip last_ts = 453.746063 last_received_ts = 453.745697 last_seq = 117 current_ts = 453.762726 current_received_ts = 453.762299 current_seq = 118 count = 25 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 25us (kms_flip:17616) DEBUG: name = flip last_ts = 453.762726 last_received_ts = 453.762299 last_seq = 118 current_ts = 453.779419 current_received_ts = 453.778900 current_seq = 119 count = 26 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 25us (kms_flip:17616) DEBUG: name = flip last_ts = 453.779419 last_received_ts = 453.778900 last_seq = 119 current_ts = 453.796082 current_received_ts = 453.795715 current_seq = 120 count = 27 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 25us (kms_flip:17616) DEBUG: name = flip last_ts = 453.796082 last_received_ts = 453.795715 last_seq = 120 current_ts = 453.812744 current_received_ts = 453.812378 current_seq = 121 count = 28 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 25us (kms_flip:17616) DEBUG: name = flip last_ts = 453.812744 last_received_ts = 453.812378 last_seq = 121 current_ts = 453.829407 current_received_ts = 453.828918 current_seq = 122 count = 29 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 25us (kms_flip:17616) DEBUG: name = flip last_ts = 453.829407 last_received_ts = 453.828918 last_seq = 122 current_ts = 453.846100 current_received_ts = 453.845581 current_seq = 123 count = 30 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 26us (kms_flip:17616) DEBUG: name = flip last_ts = 453.846100 last_received_ts = 453.845581 last_seq = 123 current_ts = 453.862762 current_received_ts = 453.862305 current_seq = 124 count = 31 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 31us (kms_flip:17616) DEBUG: name = flip last_ts = 453.862762 last_received_ts = 453.862305 last_seq = 124 current_ts = 453.879425 current_received_ts = 453.879059 current_seq = 125 count = 32 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 31us (kms_flip:17616) DEBUG: name = flip last_ts = 453.879425 last_received_ts = 453.879059 last_seq = 125 current_ts = 453.896088 current_received_ts = 453.895721 current_seq = 126 count = 33 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 31us (kms_flip:17616) DEBUG: name = flip last_ts = 453.896088 last_received_ts = 453.895721 last_seq = 126 current_ts = 453.912750 current_received_ts = 453.912445 current_seq = 127 count = 34 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 219us (kms_flip:17616) DEBUG: name = flip last_ts = 453.912750 last_received_ts = 453.912445 last_seq = 127 current_ts = 453.929443 current_received_ts = 453.929077 current_seq = 128 count = 35 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 75us (kms_flip:17616) DEBUG: name = flip last_ts = 453.929443 last_received_ts = 453.929077 last_seq = 128 current_ts = 453.946106 current_received_ts = 453.945740 current_seq = 129 count = 36 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 280us (kms_flip:17616) DEBUG: name = flip last_ts = 453.946106 last_received_ts = 453.945740 last_seq = 129 current_ts = 453.962769 current_received_ts = 453.962402 current_seq = 130 count = 37 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 253us (kms_flip:17616) DEBUG: name = flip last_ts = 453.962769 last_received_ts = 453.962402 last_seq = 130 current_ts = 453.979431 current_received_ts = 453.979065 current_seq = 131 count = 38 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 250us (kms_flip:17616) DEBUG: name = flip last_ts = 453.979431 last_received_ts = 453.979065 last_seq = 131 current_ts = 453.996094 current_received_ts = 453.995728 current_seq = 132 count = 39 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 254us (kms_flip:17616) DEBUG: name = flip last_ts = 453.996094 last_received_ts = 453.995728 last_seq = 132 current_ts = 454.012787 current_received_ts = 454.012390 current_seq = 133 count = 40 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 261us (kms_flip:17616) DEBUG: name = flip last_ts = 454.012787 last_received_ts = 454.012390 last_seq = 133 current_ts = 454.029449 current_received_ts = 454.029053 current_seq = 134 count = 41 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 245us (kms_flip:17616) DEBUG: name = flip last_ts = 454.029449 last_received_ts = 454.029053 last_seq = 134 current_ts = 454.046112 current_received_ts = 454.045746 current_seq = 135 count = 42 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 230us (kms_flip:17616) DEBUG: name = flip last_ts = 454.046112 last_received_ts = 454.045746 last_seq = 135 current_ts = 454.062775 current_received_ts = 454.062408 current_seq = 136 count = 43 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 235us (kms_flip:17616) DEBUG: name = flip last_ts = 454.062775 last_received_ts = 454.062408 last_seq = 136 current_ts = 454.079437 current_received_ts = 454.079071 current_seq = 137 count = 44 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 234us (kms_flip:17616) DEBUG: name = flip last_ts = 454.079437 last_received_ts = 454.079071 last_seq = 137 current_ts = 454.096100 current_received_ts = 454.095734 current_seq = 138 count = 45 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 235us (kms_flip:17616) DEBUG: name = flip last_ts = 454.096100 last_received_ts = 454.095734 last_seq = 138 current_ts = 454.112793 current_received_ts = 454.112396 current_seq = 139 count = 46 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 247us (kms_flip:17616) DEBUG: name = flip last_ts = 454.112793 last_received_ts = 454.112396 last_seq = 139 current_ts = 454.129456 current_received_ts = 454.129089 current_seq = 140 count = 47 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 232us (kms_flip:17616) DEBUG: name = flip last_ts = 454.129456 last_received_ts = 454.129089 last_seq = 140 current_ts = 454.146118 current_received_ts = 454.145752 current_seq = 141 count = 48 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 235us (kms_flip:17616) DEBUG: name = flip last_ts = 454.146118 last_received_ts = 454.145752 last_seq = 141 current_ts = 454.162781 current_received_ts = 454.162415 current_seq = 142 count = 49 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 242us (kms_flip:17616) DEBUG: name = flip last_ts = 454.162781 last_received_ts = 454.162415 last_seq = 142 current_ts = 454.179474 current_received_ts = 454.179169 current_seq = 143 count = 50 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 236us (kms_flip:17616) DEBUG: name = flip last_ts = 454.179474 last_received_ts = 454.179169 last_seq = 143 current_ts = 454.196136 current_received_ts = 454.195831 current_seq = 144 count = 51 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 249us (kms_flip:17616) DEBUG: name = flip last_ts = 454.196136 last_received_ts = 454.195831 last_seq = 144 current_ts = 454.212799 current_received_ts = 454.212494 current_seq = 145 count = 52 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 241us (kms_flip:17616) DEBUG: name = flip last_ts = 454.212799 last_received_ts = 454.212494 last_seq = 145 current_ts = 454.229492 current_received_ts = 454.229340 current_seq = 146 count = 53 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 74us (kms_flip:17616) DEBUG: name = flip last_ts = 454.229492 last_received_ts = 454.229340 last_seq = 146 current_ts = 454.246155 current_received_ts = 454.245819 current_seq = 147 count = 54 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 246us (kms_flip:17616) DEBUG: name = flip last_ts = 454.246155 last_received_ts = 454.245819 last_seq = 147 current_ts = 454.262817 current_received_ts = 454.262512 current_seq = 148 count = 55 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 239us (kms_flip:17616) DEBUG: name = flip last_ts = 454.262817 last_received_ts = 454.262512 last_seq = 148 current_ts = 454.279480 current_received_ts = 454.279144 current_seq = 149 count = 56 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 230us (kms_flip:17616) DEBUG: name = flip last_ts = 454.279480 last_received_ts = 454.279144 last_seq = 149 current_ts = 454.296143 current_received_ts = 454.295837 current_seq = 150 count = 57 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 247us (kms_flip:17616) DEBUG: name = flip last_ts = 454.296143 last_received_ts = 454.295837 last_seq = 150 current_ts = 454.312836 current_received_ts = 454.312500 current_seq = 151 count = 58 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 238us (kms_flip:17616) DEBUG: name = flip last_ts = 454.312836 last_received_ts = 454.312500 last_seq = 151 current_ts = 454.329498 current_received_ts = 454.329163 current_seq = 152 count = 59 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 239us (kms_flip:17616) DEBUG: name = flip last_ts = 454.329498 last_received_ts = 454.329163 last_seq = 152 current_ts = 454.346161 current_received_ts = 454.345856 current_seq = 153 count = 60 seq_step = 1 (kms_flip:17616) DEBUG: Vblank took 510us (kms_flip:17616) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:926: (kms_flip:17616) CRITICAL: Failed assertion: end - start < 500 (kms_flip:17616) CRITICAL: Last errno: 4, Interrupted system call (kms_flip:17616) igt_core-INFO: Stack trace: (kms_flip:17616) igt_core-INFO: #0 ../lib/igt_core.c:2075 __igt_fail_assert() (kms_flip:17616) igt_core-INFO: #1 ../tests/kms_flip.c:921 run_test_on_crtc_set.constprop.0() (kms_flip:17616) igt_core-INFO: #2 ../tests/kms_flip.c:1887 run_test() (kms_flip:17616) igt_core-INFO: #3 ../tests/kms_flip.c:2156 __igt_unique____real_main2043() (kms_flip:17616) igt_core-INFO: #4 ../tests/kms_flip.c:2043 main() (kms_flip:17616) igt_core-INFO: #5 [__libc_init_first+0x8a] (kms_flip:17616) igt_core-INFO: #6 [__libc_start_main+0x8b] (kms_flip:17616) igt_core-INFO: #7 [_start+0x25] END ** Dynamic subtest C-DP2: FAIL (1.266s)
Dmesg	<6> [452.767029] [IGT] kms_flip: starting dynamic subtest C-DP2 <7> [452.769842] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:555] <7> [452.771375] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:557] <7> [452.815332] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:150:pipe A] <7> [452.815443] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:268:pipe B] <7> [452.815508] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.enable (expected yes, found no) <7> [452.815631] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.active (expected yes, found no) <7> [452.815738] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1) <7> [452.815841] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in lane_count (expected 4, found 0) <7> [452.815938] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in dp_m_n (expected tu 64 data 5767168/8388608 link 480597/524288, found tu 0, data 0/0 link 0/0) <7> [452.816035] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in output_types (expected 0x00000080, found 0x00000000) <7> [452.816128] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0) <7> [452.816229] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0) <7> [452.816321] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0) <7> [452.816411] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0) <7> [452.816502] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0) <7> [452.816592] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0) <7> [452.816681] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0) <7> [452.816771] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0) <7> [452.816860] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0) <7> [452.816948] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0) <7> [452.817036] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0) <7> [452.817124] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0) <7> [452.817220] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0) <7> [452.817309] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0) <7> [452.817397] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0) <7> [452.817485] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0) <7> [452.817572] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0) <7> [452.817660] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0) <7> [452.817746] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0) <7> [452.817834] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0) <7> [452.817921] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0) <7> [452.818008] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0) <7> [452.818096] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0) <7> [452.818183] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0) <7> [452.818292] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0) <7> [452.818381] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0) <7> [452.818470] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0) <7> [452.818558] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0) <7> [452.818647] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in enhanced_framing (expected yes, found no) <7> [452.818736] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in pipe_bpp (expected 24, found 0) <7> [452.818825] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0) <7> [452.818912] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0) <7> [452.819000] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in port_clock (expected 162000, found 0) <7> [452.819088] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:268:pipe B] fastset requirement not met, forcing full modeset <7> [452.819178] xe 0000:03:00.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:268:pipe B] releasing TC PLL 2 <7> [452.819341] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no <7> [452.819423] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:268:pipe B] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x2 -> 0x0 <7> [452.819516] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:262:cursor B] ddb (4037 - 4096) -> ( 0 - 0), size 59 -> 0 <7> [452.819602] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:268:pipe B] min cdclk: 192000 kHz -> 0 kHz <7> [452.819717] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:268:pipe B] enable: no [modeset] <7> [452.819818] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:152:plane 1B] fb: [NOFB], visible: no <7> [452.819914] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:182:plane 2B] fb: [NOFB], visible: no <7> [452.820003] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:212:plane 3B] fb: [NOFB], visible: no <7> [452.820095] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:242:plane 4B] fb: [NOFB], visible: no <7> [452.820187] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:252:plane 5B] fb: [NOFB], visible: no <7> [452.820294] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:262:cursor B] fb: [NOFB], visible: no <7> [452.820965] xe 0000:03:00.0: [drm:intel_audio_codec_disable [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G] Disable audio codec on [CRTC:268:pipe B] <7> [452.845846] xe 0000:03:00.0: [drm:intel_disable_transcoder [xe]] disabling pipe B <7> [452.867272] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2 <7> [452.870389] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disable TC PLL 2 (active 0x2, on? 1) for [CRTC:268:pipe B] <7> [452.872784] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disabling TC PLL 2 <7> [452.873583] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:506:DDI TC1/PHY F] <7> [452.874031] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:508:DP-MST A] <7> [452.874514] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST B] <7> [452.874936] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST C] <7> [452.875376] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST D] <7> [452.875797] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:525:DDI TC2/PHY G] <7> [452.876432] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:527:DP-MST A] <7> [452.876817] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST B] <7> [452.877169] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST C] <7> [452.877561] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST D] <7> [452.877915] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:537:DDI TC3/PHY H] <7> [452.878245] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:541:DDI TC4/PHY I] <7> [452.878555] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:543:DP-MST A] <7> [452.878861] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST B] <7> [452.879155] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST C] <7> [452.879467] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST D] <7> [452.879745] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:526:DP-2] <7> [452.880297] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no) <7> [452.880754] xe 0000:03:00.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: ) <7> [452.880986] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1 <7> [452.881565] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_B <7> [452.882057] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x2 (CLKGATE_DIS_DSSDSC=0xaa000000) <7> [452.882561] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_2 <7> [452.883037] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:268:pipe B] <7> [452.883674] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:386:pipe C] <7> [452.884296] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:504:pipe D] <7> [452.884484] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:386:pipe C] <7> [452.884522] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:526:DP-2] <7> [452.884638] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CONNECTOR:526:DP-2] Limiting target display pipe bpp to 24 (EDID bpp 48, max requested bpp 24, max platform bpp 36) <7> [452.884846] xe 0000:03:00.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:525:DDI TC2/PHY G][CRTC:386:pipe C] DP link limits: pixel clock 148500 kHz DSC off max lanes 4 max rate 540000 max pipe_bpp 24 min link_bpp 18.0000 max link_bpp 24.0000 <7> [452.885035] xe 0000:03:00.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 4 clock 162000 bpp input 24 compressed 0.0000 HDR no link rate required 445500 available 648000 <7> [452.885223] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:386:pipe C] hw max bpp: 24, pipe bpp: 24, dithering: 0 <7> [452.885402] xe 0000:03:00.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:525:DDI TC2/PHY G] [CRTC:386:pipe C] <7> [452.885585] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.enable (expected no, found yes) <7> [452.885755] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.active (expected no, found yes) <7> [452.885921] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2) <7> [452.886075] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in lane_count (expected 0, found 4) <7> [452.886228] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 5767168/8388608 link 480597/524288) <7> [452.886370] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000080) <7> [452.886510] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1) <7> [452.886646] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920) <7> [452.886778] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200) <7> [452.886908] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920) <7> [452.887037] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200) <7> [452.887165] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008) <7> [452.887297] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052) <7> [452.887419] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080) <7> [452.887536] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080) <7> [452.887653] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084) <7> [452.887767] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089) <7> [452.887880] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125) <7> [452.887993] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125) <7> [452.888105] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920) <7> [452.888214] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200) <7> [452.888321] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920) <7> [452.888426] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200) <7> [452.888529] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008) <7> [452.888628] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052) <7> [452.888724] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080) <7> [452.888821] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080) <7> [452.888918] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084) <7> [452.889015] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089) <7> [452.889107] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125) <7> [452.889199] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125) <7> [452.889287] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1) <7> [452.889372] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1) <7> [452.889458] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4) <7> [452.889544] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in enhanced_framing (expected no, found yes) <7> [452.889631] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 24) <7> [452.889716] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500) <7> [452.889804] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500) <7> [452.889888] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in port_clock (expected 0, found 162000) <7> [452.889977] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:386:pipe C] fastset requirement not met, forcing full modeset <7> [452.890082] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xd (total dbuf slices 0xf), mbus joined? no->no <7> [452.890161] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:386:pipe C] dbuf slices 0x0 -> 0xc, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x4 <7> [452.890263] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:270:plane 1C] ddb ( 0 - 0) -> ( 0 - 1989), size 0 -> 1989 <7> [452.890338] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:380:cursor C] ddb ( 0 - 0) -> (1989 - 2048), size 0 -> 59 <7> [452.890410] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:270:plane 1C] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> wm0,wm1,wm2,wm3,wm4,wm5, wm6, wm7,twm,swm,stwm <7> [452.890482] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:270:plane 1C] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 4, 9, 9, 17, 17, 17, 17, 0, 15, 0 <7> [452.890554] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:270:plane 1C] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 62, 62, 139, 139, 261, 261, 261, 261, 137, 230, 244 <7> [452.890624] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:270:plane 1C] 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> [452.890704] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:386:pipe C] data rate 594000 num active planes 1 <7> [452.890805] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 624 qgv_peak_bw: 48000 <7> [452.890901] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 624 qgv_peak_bw: 48000 <7> [452.890997] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 624 <7> [452.891100] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:386:pipe C] min cdclk: 0 kHz -> 192000 kHz <7> [452.891221] xe 0000:03:00.0: [drm:intel_find_dpll [xe]] [CRTC:386:pipe C] allocated TC PLL 2 <7> [452.891312] xe 0000:03:00.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:386:pipe C] reserving TC PLL 2 <7> [452.891402] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:386:pipe C] enable: yes [modeset] <7> [452.891501] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: DP (0x80), output format: RGB, sink format: RGB <7> [452.891598] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: C, pipe bpp: 24, dithering: 0 <7> [452.891693] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid> <7> [452.891786] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0 <7> [452.891879] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0 <7> [452.891970] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0 <7> [452.892062] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] dp m_n: lanes: 4; data_m: 5767168, data_n: 8388608, link_m: 480597, link_n: 524288, tu: 64 <7> [452.892153] 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> [452.892253] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: enabled <7> [452.892344] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled <7> [452.892435] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] psr: disabled, selective update: disabled, panel replay: disabled, selective fetch: disabled <7> [452.892522] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0 <7> [452.892612] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 0, infoframes enabled: 0x0 <7> [452.892698] 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> [452.892789] 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> [452.892876] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 17 50 51 07 00 00 00 00 <7> [452.892965] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1 <7> [452.893055] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0 <7> [452.893142] 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> [452.893236] 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> [452.893326] 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> [452.893415] 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> [452.893505] 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> [452.893599] 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> [452.893692] 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> [452.893780] 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> [452.893870] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port clock: 162000, pipe src: 1920x1080+0+0, pixel rate 148500, min cdclk 192000 <7> [452.893957] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0 <7> [452.894046] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0 <7> [452.894132] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no <7> [452.894228] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0 <7> [452.894318] 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> [452.894408] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] c20pll_hw_state: <7> [452.894496] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] tx[0] = 0xbe88, tx[1] = 0x5800, tx[2] = 0x0000 <7> [452.894584] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000 <7> [452.894673] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[0] = 0x50a8 <7> [452.894761] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[1] = 0x2120 <7> [452.894849] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[2] = 0xcd9a <7> [452.894937] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[3] = 0xbfc1 <7> [452.895024] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[4] = 0x5ab8 <7> [452.895112] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[5] = 0x4c34 <7> [452.895198] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[6] = 0x2000 <7> [452.895285] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[7] = 0x0001 <7> [452.895371] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[8] = 0x6000 <7> [452.895459] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[9] = 0x0000 <7> [452.895546] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[10] = 0x0000 <7> [452.895633] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vdr: custom width: 0x00, serdes rate: 0x40, hdmi rate: 0x00 <7> [452.895719] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0 <7> [452.895807] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries <7> [452.895894] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000 <7> [452.895981] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000 <7> [452.896068] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000 <7> [452.896156] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000 <7> [452.896251] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000 <7> [452.896339] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000 <7> [452.896427] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [452.896512] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [452.896600] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [452.896687] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000 <7> [452.896774] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0 <7> [452.896860] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:270:plane 1C] fb: [FB:555] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes <7> [452.896944] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0 <7> [452.897029] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0 <7> [452.897117] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:300:plane 2C] fb: [NOFB], visible: no <7> [452.897206] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:330:plane 3C] fb: [NOFB], visible: no <7> [452.897293] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:360:plane 4C] fb: [NOFB], visible: no <7> [452.897377] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:370:plane 5C] fb: [NOFB], visible: no <7> [452.897460] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:380:cursor C] fb: [NOFB], visible: no <7> [452.898059] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_2 <7> [452.898203] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_C <7> [452.898340] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x4 (CLKGATE_DIS_DSSDSC=0x8a000000) <7> [452.898445] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:506:DDI TC1/PHY F] <7> [452.898532] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:508:DP-MST A] <7> [452.898615] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST B] <7> [452.898696] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST C] <7> [452.898777] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST D] <7> [452.898856] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:525:DDI TC2/PHY G] <7> [452.898934] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:527:DP-MST A] <7> [452.899012] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST B] <7> [452.899089] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST C] <7> [452.899166] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST D] <7> [452.899248] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:537:DDI TC3/PHY H] <7> [452.899325] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:541:DDI TC4/PHY I] <7> [452.899402] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:543:DP-MST A] <7> [452.899478] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST B] <7> [452.899554] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST C] <7> [452.899628] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST D] <7> [452.899713] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xd <7> [452.899875] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no) <7> [452.899973] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enable TC PLL 2 (active 0x4, on? 0) for [CRTC:386:pipe C] <7> [452.900064] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enabling TC PLL 2 <7> [452.904541] 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> [452.909289] xe 0000:03:00.0: [drm:intel_dp_init_lttpr_and_dprx_caps [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] LTTPR common capabilities: 00 00 00 00 00 00 00 00 <7> [452.910293] 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> [452.910727] xe 0000:03:00.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] Using LINK_BW_SET value 06 <7> [452.912222] xe 0000:03:00.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0 <7> [452.912773] xe 0000:03:00.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] Using DP training pattern TPS1 <7> [452.919103] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] Clock recovery OK <7> [452.919225] xe 0000:03:00.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] Using DP training pattern TPS3 <7> [452.926105] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] Channel EQ done. DP Training successful <7> [452.926320] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] Link Training passed at link rate = 162000, lane count = 4 <7> [452.927616] xe 0000:03:00.0: [drm:intel_enable_transcoder [xe]] enabling pipe C <7> [452.945327] xe 0000:03:00.0: [drm:intel_audio_codec_enable [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G] Enable audio codec on [CRTC:386:pipe C], 40 bytes ELD <7> [452.961917] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:526:DP-2] <7> [452.962825] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:386:pipe C] <7> [453.030294] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2 <6> [454.033586] [IGT] kms_flip: finished subtest C-DP2, FAIL

Detail

Value

Duration

1.27 seconds

Hostname

shard-bmg-9

Igt-Version

IGT-Version: 2.3-g46cb55353 (x86_64) (Linux: 7.0.0-rc1-lgci-xe-xe-4617-3b1923ab37ecd72e1-debug+ x86_64)

Out

Starting dynamic subtest: C-DP2
  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:921 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1887 run_test()
  #3 ../tests/kms_flip.c:2156 __igt_unique____real_main2043()
  #4 ../tests/kms_flip.c:2043 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest C-DP2: FAIL (1.266s)

Err

Starting dynamic subtest: C-DP2
(kms_flip:17616) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:926:
(kms_flip:17616) CRITICAL: Failed assertion: end - start < 500
(kms_flip:17616) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest C-DP2 failed.
**** DEBUG ****
(kms_flip:17616) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x100000000000009, size=0)
(kms_flip:17616) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:17616) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:17616) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=1, pitch=7680)
(kms_flip:17616) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:17616) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x100000000000009, size=0)
(kms_flip:17616) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:17616) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:17616) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=7680)
(kms_flip:17616) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:17616) intel_allocator_simple-DEBUG: Using simple allocator
(kms_flip:17616) intel_allocator_simple-DEBUG: <ial: 0x56102f92d6f0, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:17616) intel_allocator_simple-DEBUG: <ial: 0x56102f92d6f0, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:17616) intel_allocator_simple-DEBUG: Using simple allocator
(kms_flip:17616) intel_allocator_simple-DEBUG: <ial: 0x56102f92aea0, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:17616) intel_allocator_simple-DEBUG: <ial: 0x56102f92aea0, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:17616) intel_allocator_simple-DEBUG: Using simple allocator
(kms_flip:17616) intel_allocator_simple-DEBUG: <ial: 0x56102f92d6f0, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:17616) intel_allocator_simple-DEBUG: <ial: 0x56102f92d6f0, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:17616) intel_allocator_simple-DEBUG: Using simple allocator
(kms_flip:17616) intel_allocator_simple-DEBUG: <ial: 0x56102f92aea0, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:17616) intel_allocator_simple-DEBUG: <ial: 0x56102f92aea0, fd: 5> objects: 3, reserved_areas: 0
(kms_flip:17616) igt_kms-INFO:   1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
(kms_flip:17616) DEBUG: No stale events found
(kms_flip:17616) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:17616) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:17616) DEBUG: name = flip
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 453.346008
current_received_ts = 453.345642
current_seq = 93
count = 0
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 39us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.346008
last_received_ts = 453.345642
last_seq = 93
current_ts = 453.362671
current_received_ts = 453.362305
current_seq = 94
count = 1
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 28us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.362671
last_received_ts = 453.362305
last_seq = 94
current_ts = 453.379333
current_received_ts = 453.378967
current_seq = 95
count = 2
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 39us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.379333
last_received_ts = 453.378967
last_seq = 95
current_ts = 453.396027
current_received_ts = 453.395630
current_seq = 96
count = 3
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 27us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.396027
last_received_ts = 453.395630
last_seq = 96
current_ts = 453.412689
current_received_ts = 453.412231
current_seq = 97
count = 4
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 26us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.412689
last_received_ts = 453.412231
last_seq = 97
current_ts = 453.429352
current_received_ts = 453.428986
current_seq = 98
count = 5
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 39us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.429352
last_received_ts = 453.428986
last_seq = 98
current_ts = 453.446014
current_received_ts = 453.445587
current_seq = 99
count = 6
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 25us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.446014
last_received_ts = 453.445587
last_seq = 99
current_ts = 453.462708
current_received_ts = 453.462311
current_seq = 100
count = 7
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 25us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.462708
last_received_ts = 453.462311
last_seq = 100
current_ts = 453.479370
current_received_ts = 453.479004
current_seq = 101
count = 8
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 37us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.479370
last_received_ts = 453.479004
last_seq = 101
current_ts = 453.496033
current_received_ts = 453.495575
current_seq = 102
count = 9
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 26us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.496033
last_received_ts = 453.495575
last_seq = 102
current_ts = 453.512695
current_received_ts = 453.512207
current_seq = 103
count = 10
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 26us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.512695
last_received_ts = 453.512207
last_seq = 103
current_ts = 453.529358
current_received_ts = 453.528992
current_seq = 104
count = 11
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 24us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.529358
last_received_ts = 453.528992
last_seq = 104
current_ts = 453.546051
current_received_ts = 453.545593
current_seq = 105
count = 12
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 25us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.546051
last_received_ts = 453.545593
last_seq = 105
current_ts = 453.562714
current_received_ts = 453.562347
current_seq = 106
count = 13
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 25us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.562714
last_received_ts = 453.562347
last_seq = 106
current_ts = 453.579376
current_received_ts = 453.579010
current_seq = 107
count = 14
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 24us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.579376
last_received_ts = 453.579010
last_seq = 107
current_ts = 453.596039
current_received_ts = 453.595612
current_seq = 108
count = 15
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 25us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.596039
last_received_ts = 453.595612
last_seq = 108
current_ts = 453.612701
current_received_ts = 453.612274
current_seq = 109
count = 16
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 26us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.612701
last_received_ts = 453.612274
last_seq = 109
current_ts = 453.629395
current_received_ts = 453.628906
current_seq = 110
count = 17
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 25us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.629395
last_received_ts = 453.628906
last_seq = 110
current_ts = 453.646057
current_received_ts = 453.645660
current_seq = 111
count = 18
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 26us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.646057
last_received_ts = 453.645660
last_seq = 111
current_ts = 453.662720
current_received_ts = 453.662262
current_seq = 112
count = 19
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 26us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.662720
last_received_ts = 453.662262
last_seq = 112
current_ts = 453.679382
current_received_ts = 453.678894
current_seq = 113
count = 20
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 25us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.679382
last_received_ts = 453.678894
last_seq = 113
current_ts = 453.696045
current_received_ts = 453.695679
current_seq = 114
count = 21
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 26us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.696045
last_received_ts = 453.695679
last_seq = 114
current_ts = 453.712738
current_received_ts = 453.712250
current_seq = 115
count = 22
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 26us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.712738
last_received_ts = 453.712250
last_seq = 115
current_ts = 453.729401
current_received_ts = 453.728912
current_seq = 116
count = 23
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 25us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.729401
last_received_ts = 453.728912
last_seq = 116
current_ts = 453.746063
current_received_ts = 453.745697
current_seq = 117
count = 24
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 26us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.746063
last_received_ts = 453.745697
last_seq = 117
current_ts = 453.762726
current_received_ts = 453.762299
current_seq = 118
count = 25
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 25us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.762726
last_received_ts = 453.762299
last_seq = 118
current_ts = 453.779419
current_received_ts = 453.778900
current_seq = 119
count = 26
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 25us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.779419
last_received_ts = 453.778900
last_seq = 119
current_ts = 453.796082
current_received_ts = 453.795715
current_seq = 120
count = 27
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 25us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.796082
last_received_ts = 453.795715
last_seq = 120
current_ts = 453.812744
current_received_ts = 453.812378
current_seq = 121
count = 28
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 25us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.812744
last_received_ts = 453.812378
last_seq = 121
current_ts = 453.829407
current_received_ts = 453.828918
current_seq = 122
count = 29
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 25us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.829407
last_received_ts = 453.828918
last_seq = 122
current_ts = 453.846100
current_received_ts = 453.845581
current_seq = 123
count = 30
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 26us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.846100
last_received_ts = 453.845581
last_seq = 123
current_ts = 453.862762
current_received_ts = 453.862305
current_seq = 124
count = 31
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 31us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.862762
last_received_ts = 453.862305
last_seq = 124
current_ts = 453.879425
current_received_ts = 453.879059
current_seq = 125
count = 32
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 31us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.879425
last_received_ts = 453.879059
last_seq = 125
current_ts = 453.896088
current_received_ts = 453.895721
current_seq = 126
count = 33
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 31us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.896088
last_received_ts = 453.895721
last_seq = 126
current_ts = 453.912750
current_received_ts = 453.912445
current_seq = 127
count = 34
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 219us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.912750
last_received_ts = 453.912445
last_seq = 127
current_ts = 453.929443
current_received_ts = 453.929077
current_seq = 128
count = 35
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 75us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.929443
last_received_ts = 453.929077
last_seq = 128
current_ts = 453.946106
current_received_ts = 453.945740
current_seq = 129
count = 36
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 280us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.946106
last_received_ts = 453.945740
last_seq = 129
current_ts = 453.962769
current_received_ts = 453.962402
current_seq = 130
count = 37
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 253us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.962769
last_received_ts = 453.962402
last_seq = 130
current_ts = 453.979431
current_received_ts = 453.979065
current_seq = 131
count = 38
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 250us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.979431
last_received_ts = 453.979065
last_seq = 131
current_ts = 453.996094
current_received_ts = 453.995728
current_seq = 132
count = 39
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 254us
(kms_flip:17616) DEBUG: name = flip
last_ts = 453.996094
last_received_ts = 453.995728
last_seq = 132
current_ts = 454.012787
current_received_ts = 454.012390
current_seq = 133
count = 40
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 261us
(kms_flip:17616) DEBUG: name = flip
last_ts = 454.012787
last_received_ts = 454.012390
last_seq = 133
current_ts = 454.029449
current_received_ts = 454.029053
current_seq = 134
count = 41
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 245us
(kms_flip:17616) DEBUG: name = flip
last_ts = 454.029449
last_received_ts = 454.029053
last_seq = 134
current_ts = 454.046112
current_received_ts = 454.045746
current_seq = 135
count = 42
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 230us
(kms_flip:17616) DEBUG: name = flip
last_ts = 454.046112
last_received_ts = 454.045746
last_seq = 135
current_ts = 454.062775
current_received_ts = 454.062408
current_seq = 136
count = 43
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 235us
(kms_flip:17616) DEBUG: name = flip
last_ts = 454.062775
last_received_ts = 454.062408
last_seq = 136
current_ts = 454.079437
current_received_ts = 454.079071
current_seq = 137
count = 44
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 234us
(kms_flip:17616) DEBUG: name = flip
last_ts = 454.079437
last_received_ts = 454.079071
last_seq = 137
current_ts = 454.096100
current_received_ts = 454.095734
current_seq = 138
count = 45
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 235us
(kms_flip:17616) DEBUG: name = flip
last_ts = 454.096100
last_received_ts = 454.095734
last_seq = 138
current_ts = 454.112793
current_received_ts = 454.112396
current_seq = 139
count = 46
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 247us
(kms_flip:17616) DEBUG: name = flip
last_ts = 454.112793
last_received_ts = 454.112396
last_seq = 139
current_ts = 454.129456
current_received_ts = 454.129089
current_seq = 140
count = 47
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 232us
(kms_flip:17616) DEBUG: name = flip
last_ts = 454.129456
last_received_ts = 454.129089
last_seq = 140
current_ts = 454.146118
current_received_ts = 454.145752
current_seq = 141
count = 48
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 235us
(kms_flip:17616) DEBUG: name = flip
last_ts = 454.146118
last_received_ts = 454.145752
last_seq = 141
current_ts = 454.162781
current_received_ts = 454.162415
current_seq = 142
count = 49
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 242us
(kms_flip:17616) DEBUG: name = flip
last_ts = 454.162781
last_received_ts = 454.162415
last_seq = 142
current_ts = 454.179474
current_received_ts = 454.179169
current_seq = 143
count = 50
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 236us
(kms_flip:17616) DEBUG: name = flip
last_ts = 454.179474
last_received_ts = 454.179169
last_seq = 143
current_ts = 454.196136
current_received_ts = 454.195831
current_seq = 144
count = 51
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 249us
(kms_flip:17616) DEBUG: name = flip
last_ts = 454.196136
last_received_ts = 454.195831
last_seq = 144
current_ts = 454.212799
current_received_ts = 454.212494
current_seq = 145
count = 52
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 241us
(kms_flip:17616) DEBUG: name = flip
last_ts = 454.212799
last_received_ts = 454.212494
last_seq = 145
current_ts = 454.229492
current_received_ts = 454.229340
current_seq = 146
count = 53
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 74us
(kms_flip:17616) DEBUG: name = flip
last_ts = 454.229492
last_received_ts = 454.229340
last_seq = 146
current_ts = 454.246155
current_received_ts = 454.245819
current_seq = 147
count = 54
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 246us
(kms_flip:17616) DEBUG: name = flip
last_ts = 454.246155
last_received_ts = 454.245819
last_seq = 147
current_ts = 454.262817
current_received_ts = 454.262512
current_seq = 148
count = 55
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 239us
(kms_flip:17616) DEBUG: name = flip
last_ts = 454.262817
last_received_ts = 454.262512
last_seq = 148
current_ts = 454.279480
current_received_ts = 454.279144
current_seq = 149
count = 56
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 230us
(kms_flip:17616) DEBUG: name = flip
last_ts = 454.279480
last_received_ts = 454.279144
last_seq = 149
current_ts = 454.296143
current_received_ts = 454.295837
current_seq = 150
count = 57
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 247us
(kms_flip:17616) DEBUG: name = flip
last_ts = 454.296143
last_received_ts = 454.295837
last_seq = 150
current_ts = 454.312836
current_received_ts = 454.312500
current_seq = 151
count = 58
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 238us
(kms_flip:17616) DEBUG: name = flip
last_ts = 454.312836
last_received_ts = 454.312500
last_seq = 151
current_ts = 454.329498
current_received_ts = 454.329163
current_seq = 152
count = 59
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 239us
(kms_flip:17616) DEBUG: name = flip
last_ts = 454.329498
last_received_ts = 454.329163
last_seq = 152
current_ts = 454.346161
current_received_ts = 454.345856
current_seq = 153
count = 60
seq_step = 1
(kms_flip:17616) DEBUG: Vblank took 510us
(kms_flip:17616) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:926:
(kms_flip:17616) CRITICAL: Failed assertion: end - start < 500
(kms_flip:17616) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:17616) igt_core-INFO: Stack trace:
(kms_flip:17616) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:17616) igt_core-INFO:   #1 ../tests/kms_flip.c:921 run_test_on_crtc_set.constprop.0()
(kms_flip:17616) igt_core-INFO:   #2 ../tests/kms_flip.c:1887 run_test()
(kms_flip:17616) igt_core-INFO:   #3 ../tests/kms_flip.c:2156 __igt_unique____real_main2043()
(kms_flip:17616) igt_core-INFO:   #4 ../tests/kms_flip.c:2043 main()
(kms_flip:17616) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:17616) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:17616) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest C-DP2: FAIL (1.266s)

Dmesg

<6> [452.767029] [IGT] kms_flip: starting dynamic subtest C-DP2

<7> [452.769842] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:555]

<7> [452.771375] xe 0000:03:00.0: [drm:drm_mode_addfb2] [FB:557]

<7> [452.815332] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:150:pipe A]

<7> [452.815443] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:268:pipe B]

<7> [452.815508] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.enable (expected yes, found no)

<7> [452.815631] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.active (expected yes, found no)

<7> [452.815738] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)

<7> [452.815841] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in lane_count (expected 4, found 0)

<7> [452.815938] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in dp_m_n (expected tu 64 data 5767168/8388608 link 480597/524288, found tu 0, data 0/0 link 0/0)

<7> [452.816035] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in output_types (expected 0x00000080, found 0x00000000)

<7> [452.816128] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)

<7> [452.816229] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)

<7> [452.816321] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)

<7> [452.816411] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)

<7> [452.816502] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)

<7> [452.816592] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)

<7> [452.816681] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)

<7> [452.816771] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)

<7> [452.816860] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)

<7> [452.816948] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)

<7> [452.817036] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)

<7> [452.817124] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)

<7> [452.817220] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)

<7> [452.817309] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)

<7> [452.817397] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)

<7> [452.817485] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)

<7> [452.817572] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)

<7> [452.817660] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)

<7> [452.817746] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)

<7> [452.817834] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)

<7> [452.817921] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)

<7> [452.818008] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)

<7> [452.818096] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)

<7> [452.818183] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)

<7> [452.818292] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)

<7> [452.818381] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)

<7> [452.818470] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)

<7> [452.818558] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)

<7> [452.818647] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in enhanced_framing (expected yes, found no)

<7> [452.818736] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in pipe_bpp (expected 24, found 0)

<7> [452.818825] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)

<7> [452.818912] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)

<7> [452.819000] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:268:pipe B] fastset requirement not met in port_clock (expected 162000, found 0)

<7> [452.819088] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:268:pipe B] fastset requirement not met, forcing full modeset

<7> [452.819178] xe 0000:03:00.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:268:pipe B] releasing TC PLL 2

<7> [452.819341] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no

<7> [452.819423] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:268:pipe B] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x2 -> 0x0

<7> [452.819516] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:262:cursor B] ddb (4037 - 4096) -> ( 0 - 0), size 59 -> 0

<7> [452.819602] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:268:pipe B] min cdclk: 192000 kHz -> 0 kHz

<7> [452.819717] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:268:pipe B] enable: no [modeset]

<7> [452.819818] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:152:plane 1B] fb: [NOFB], visible: no

<7> [452.819914] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:182:plane 2B] fb: [NOFB], visible: no

<7> [452.820003] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:212:plane 3B] fb: [NOFB], visible: no

<7> [452.820095] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:242:plane 4B] fb: [NOFB], visible: no

<7> [452.820187] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:252:plane 5B] fb: [NOFB], visible: no

<7> [452.820294] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:262:cursor B] fb: [NOFB], visible: no

<7> [452.820965] xe 0000:03:00.0: [drm:intel_audio_codec_disable [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G] Disable audio codec on [CRTC:268:pipe B]

<7> [452.845846] xe 0000:03:00.0: [drm:intel_disable_transcoder [xe]] disabling pipe B

<7> [452.867272] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2

<7> [452.870389] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disable TC PLL 2 (active 0x2, on? 1) for [CRTC:268:pipe B]

<7> [452.872784] xe 0000:03:00.0: [drm:intel_dpll_disable [xe]] disabling TC PLL 2

<7> [452.873583] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:506:DDI TC1/PHY F]

<7> [452.874031] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:508:DP-MST A]

<7> [452.874514] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST B]

<7> [452.874936] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST C]

<7> [452.875376] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST D]

<7> [452.875797] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:525:DDI TC2/PHY G]

<7> [452.876432] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:527:DP-MST A]

<7> [452.876817] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST B]

<7> [452.877169] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST C]

<7> [452.877561] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST D]

<7> [452.877915] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:537:DDI TC3/PHY H]

<7> [452.878245] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:541:DDI TC4/PHY I]

<7> [452.878555] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:543:DP-MST A]

<7> [452.878861] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST B]

<7> [452.879155] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST C]

<7> [452.879467] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST D]

<7> [452.879745] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:526:DP-2]

<7> [452.880297] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)

<7> [452.880754] xe 0000:03:00.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)

<7> [452.880986] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1

<7> [452.881565] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_B

<7> [452.882057] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating disabled for pipe_mask=0x2 (CLKGATE_DIS_DSSDSC=0xaa000000)

<7> [452.882561] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_2

<7> [452.883037] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:268:pipe B]

<7> [452.883674] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:386:pipe C]

<7> [452.884296] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:504:pipe D]

<7> [452.884484] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:386:pipe C]

<7> [452.884522] xe 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:526:DP-2]

<7> [452.884638] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CONNECTOR:526:DP-2] Limiting target display pipe bpp to 24 (EDID bpp 48, max requested bpp 24, max platform bpp 36)

<7> [452.884846] xe 0000:03:00.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:525:DDI TC2/PHY G][CRTC:386:pipe C] DP link limits: pixel clock 148500 kHz DSC off max lanes 4 max rate 540000 max pipe_bpp 24 min link_bpp 18.0000 max link_bpp 24.0000

<7> [452.885035] xe 0000:03:00.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 4 clock 162000 bpp input 24 compressed 0.0000 HDR no link rate required 445500 available 648000

<7> [452.885223] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:386:pipe C] hw max bpp: 24, pipe bpp: 24, dithering: 0

<7> [452.885402] xe 0000:03:00.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:525:DDI TC2/PHY G] [CRTC:386:pipe C]

<7> [452.885585] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.enable (expected no, found yes)

<7> [452.885755] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.active (expected no, found yes)

<7> [452.885921] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2)

<7> [452.886075] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in lane_count (expected 0, found 4)

<7> [452.886228] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 5767168/8388608 link 480597/524288)

<7> [452.886370] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000080)

<7> [452.886510] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1)

<7> [452.886646] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)

<7> [452.886778] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)

<7> [452.886908] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)

<7> [452.887037] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)

<7> [452.887165] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)

<7> [452.887297] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)

<7> [452.887419] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)

<7> [452.887536] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)

<7> [452.887653] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)

<7> [452.887767] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)

<7> [452.887880] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)

<7> [452.887993] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)

<7> [452.888105] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)

<7> [452.888214] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)

<7> [452.888321] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)

<7> [452.888426] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)

<7> [452.888529] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)

<7> [452.888628] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)

<7> [452.888724] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)

<7> [452.888821] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)

<7> [452.888918] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)

<7> [452.889015] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)

<7> [452.889107] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)

<7> [452.889199] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)

<7> [452.889287] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1)

<7> [452.889372] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)

<7> [452.889458] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)

<7> [452.889544] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in enhanced_framing (expected no, found yes)

<7> [452.889631] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 24)

<7> [452.889716] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)

<7> [452.889804] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)

<7> [452.889888] xe 0000:03:00.0: [drm:intel_pipe_config_compare [xe]] [CRTC:386:pipe C] fastset requirement not met in port_clock (expected 0, found 162000)

<7> [452.889977] xe 0000:03:00.0: [drm:intel_atomic_check [xe]] [CRTC:386:pipe C] fastset requirement not met, forcing full modeset

<7> [452.890082] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xd (total dbuf slices 0xf), mbus joined? no->no

<7> [452.890161] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [CRTC:386:pipe C] dbuf slices 0x0 -> 0xc, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x4

<7> [452.890263] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:270:plane 1C] ddb ( 0 - 0) -> ( 0 - 1989), size 0 -> 1989

<7> [452.890338] xe 0000:03:00.0: [drm:skl_compute_wm [xe]] [PLANE:380:cursor C] ddb ( 0 - 0) -> (1989 - 2048), size 0 -> 59

<7> [452.890410] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:270:plane 1C] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm

<7> [452.890482] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:270:plane 1C] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 4, 9, 9, 17, 17, 17, 17, 0, 15, 0

<7> [452.890554] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:270:plane 1C] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 62, 62, 139, 139, 261, 261, 261, 261, 137, 230, 244

<7> [452.890624] xe 0000:03:00.0: [drm:skl_print_plane_changes [xe]] [PLANE:270:plane 1C] 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> [452.890704] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] [CRTC:386:pipe C] data rate 594000 num active planes 1

<7> [452.890805] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 33600 required 624 qgv_peak_bw: 48000

<7> [452.890901] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 53000 required 624 qgv_peak_bw: 48000

<7> [452.890997] xe 0000:03:00.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 48000 for required data rate: 624

<7> [452.891100] xe 0000:03:00.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:386:pipe C] min cdclk: 0 kHz -> 192000 kHz

<7> [452.891221] xe 0000:03:00.0: [drm:intel_find_dpll [xe]] [CRTC:386:pipe C] allocated TC PLL 2

<7> [452.891312] xe 0000:03:00.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:386:pipe C] reserving TC PLL 2

<7> [452.891402] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [CRTC:386:pipe C] enable: yes [modeset]

<7> [452.891501] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: DP (0x80), output format: RGB, sink format: RGB

<7> [452.891598] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: C, pipe bpp: 24, dithering: 0

<7> [452.891693] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>

<7> [452.891786] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0

<7> [452.891879] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0

<7> [452.891970] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0

<7> [452.892062] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] dp m_n: lanes: 4; data_m: 5767168, data_n: 8388608, link_m: 480597, link_n: 524288, tu: 64

<7> [452.892153] 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> [452.892253] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: enabled

<7> [452.892344] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled

<7> [452.892435] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] psr: disabled, selective update: disabled, panel replay: disabled, selective fetch: disabled

<7> [452.892522] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0

<7> [452.892612] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] audio: 1, infoframes: 0, infoframes enabled: 0x0

<7> [452.892698] 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> [452.892789] 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> [452.892876] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ELD: 17 50 51 07 00 00 00 00

<7> [452.892965] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1

<7> [452.893055] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0, set context latency: 0

<7> [452.893142] 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> [452.893236] 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> [452.893326] 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> [452.893415] 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> [452.893505] 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> [452.893599] 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> [452.893692] 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> [452.893780] 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> [452.893870] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] port clock: 162000, pipe src: 1920x1080+0+0, pixel rate 148500, min cdclk 192000

<7> [452.893957] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0

<7> [452.894046] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0

<7> [452.894132] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no

<7> [452.894228] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0

<7> [452.894318] 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> [452.894408] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] c20pll_hw_state:

<7> [452.894496] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] tx[0] = 0xbe88, tx[1] = 0x5800, tx[2] = 0x0000

<7> [452.894584] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] cmn[0] = 0x0500, cmn[1] = 0x0005, cmn[2] = 0x0000, cmn[3] = 0x0000

<7> [452.894673] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[0] = 0x50a8

<7> [452.894761] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[1] = 0x2120

<7> [452.894849] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[2] = 0xcd9a

<7> [452.894937] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[3] = 0xbfc1

<7> [452.895024] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[4] = 0x5ab8

<7> [452.895112] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[5] = 0x4c34

<7> [452.895198] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[6] = 0x2000

<7> [452.895285] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[7] = 0x0001

<7> [452.895371] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[8] = 0x6000

<7> [452.895459] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[9] = 0x0000

<7> [452.895546] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] mpllb[10] = 0x0000

<7> [452.895633] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] vdr: custom width: 0x00, serdes rate: 0x40, hdmi rate: 0x00

<7> [452.895719] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0

<7> [452.895807] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries

<7> [452.895894] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000

<7> [452.895981] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000

<7> [452.896068] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000

<7> [452.896156] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000

<7> [452.896251] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000

<7> [452.896339] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000

<7> [452.896427] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000

<7> [452.896512] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000

<7> [452.896600] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000

<7> [452.896687] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000

<7> [452.896774] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0

<7> [452.896860] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:270:plane 1C] fb: [FB:555] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes

<7> [452.896944] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0

<7> [452.897029] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0

<7> [452.897117] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:300:plane 2C] fb: [NOFB], visible: no

<7> [452.897206] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:330:plane 3C] fb: [NOFB], visible: no

<7> [452.897293] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:360:plane 4C] fb: [NOFB], visible: no

<7> [452.897377] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:370:plane 5C] fb: [NOFB], visible: no

<7> [452.897460] xe 0000:03:00.0: [drm:intel_crtc_state_dump [xe]] [PLANE:380:cursor C] fb: [NOFB], visible: no

<7> [452.898059] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_2

<7> [452.898203] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling PW_C

<7> [452.898340] xe 0000:03:00.0: [drm:dss_pipe_gating_enable_disable [xe]] DSS clock gating enabled for pipe_mask=0x4 (CLKGATE_DIS_DSSDSC=0x8a000000)

<7> [452.898445] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:506:DDI TC1/PHY F]

<7> [452.898532] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:508:DP-MST A]

<7> [452.898615] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:509:DP-MST B]

<7> [452.898696] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:510:DP-MST C]

<7> [452.898777] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:511:DP-MST D]

<7> [452.898856] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:525:DDI TC2/PHY G]

<7> [452.898934] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:527:DP-MST A]

<7> [452.899012] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:528:DP-MST B]

<7> [452.899089] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:529:DP-MST C]

<7> [452.899166] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:530:DP-MST D]

<7> [452.899248] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:537:DDI TC3/PHY H]

<7> [452.899325] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:541:DDI TC4/PHY I]

<7> [452.899402] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:543:DP-MST A]

<7> [452.899478] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:544:DP-MST B]

<7> [452.899554] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:545:DP-MST C]

<7> [452.899628] xe 0000:03:00.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:546:DP-MST D]

<7> [452.899713] xe 0000:03:00.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xd

<7> [452.899875] xe 0000:03:00.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)

<7> [452.899973] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enable TC PLL 2 (active 0x4, on? 0) for [CRTC:386:pipe C]

<7> [452.900064] xe 0000:03:00.0: [drm:intel_dpll_enable [xe]] enabling TC PLL 2

<7> [452.904541] 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> [452.909289] xe 0000:03:00.0: [drm:intel_dp_init_lttpr_and_dprx_caps [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] LTTPR common capabilities: 00 00 00 00 00 00 00 00

<7> [452.910293] 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> [452.910727] xe 0000:03:00.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] Using LINK_BW_SET value 06

<7> [452.912222] xe 0000:03:00.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0

<7> [452.912773] xe 0000:03:00.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] Using DP training pattern TPS1

<7> [452.919103] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] Clock recovery OK

<7> [452.919225] xe 0000:03:00.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] Using DP training pattern TPS3

<7> [452.926105] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] Channel EQ done. DP Training successful

<7> [452.926320] xe 0000:03:00.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G][DPRX] Link Training passed at link rate = 162000, lane count = 4

<7> [452.927616] xe 0000:03:00.0: [drm:intel_enable_transcoder [xe]] enabling pipe C

<7> [452.945327] xe 0000:03:00.0: [drm:intel_audio_codec_enable [xe]] [CONNECTOR:526:DP-2][ENCODER:525:DDI TC2/PHY G] Enable audio codec on [CRTC:386:pipe C], 40 bytes ELD

<7> [452.961917] xe 0000:03:00.0: [drm:verify_connector_state [xe]] [CONNECTOR:526:DP-2]

<7> [452.962825] xe 0000:03:00.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:386:pipe C]

<7> [453.030294] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2

<6> [454.033586] [IGT] kms_flip: finished subtest C-DP2, FAIL

Results for igt@kms_flip@flip-vs-expired-vblank-interruptible@c-dp2