[68.562640] Initializing watchdogs
[68.562723]   /dev/watchdog0
[68.593991] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:03:00.0: 8086:4905 Intel Dg1 (Gen12) DG1 [Iris Xe MAX Graphics]
[68.610066] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[68.614974] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[68.618437] [001/156] (960s left) kms_frontbuffer_tracking (fbc-tiling-linear)
[70.128845] Starting subtest: fbc-tiling-linear
[72.882291] Subtest fbc-tiling-linear: SUCCESS (2.755s)
[73.095838] [002/156] (956s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes)
[73.147495] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s)
[73.225132] [003/156] (956s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[74.725441] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[74.728760] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[74.871522] [004/156] (954s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[76.388114] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[76.391267] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[76.546555] [005/156] (952s left) kms_force_connector_basic (prune-stale-modes)
[76.682575] Starting subtest: prune-stale-modes
[76.705904] Subtest prune-stale-modes: SUCCESS (0.023s)
[78.366969] [006/156] (951s left) perf_pmu (most-busy-check-all)
[78.434772] Starting subtest: most-busy-check-all
[78.436149] Starting dynamic subtest: rcs0
[79.013025] Dynamic subtest rcs0: SUCCESS (0.579s)
[79.026655] Starting dynamic subtest: bcs0
[79.563594] Dynamic subtest bcs0: SUCCESS (0.550s)
[79.575031] Starting dynamic subtest: vcs0
[80.111969] Dynamic subtest vcs0: SUCCESS (0.548s)
[80.123627] Starting dynamic subtest: vcs1
[80.658722] Dynamic subtest vcs1: SUCCESS (0.547s)
[80.671841] Starting dynamic subtest: vecs0
[81.207689] Dynamic subtest vecs0: SUCCESS (0.549s)
[81.209322] Subtest most-busy-check-all: SUCCESS (2.774s)
[81.339111] [007/156] (948s left) gem_ccs (large-ctrl-surf-copy)
[81.392083] Starting subtest: large-ctrl-surf-copy
[81.395122] Subtest large-ctrl-surf-copy: SKIP (0.000s)
[81.494252] [008/156] (948s left) kms_plane_alpha_blend (coverage-7efc)
[82.984397] Starting subtest: coverage-7efc
[83.262496] Starting dynamic subtest: pipe-A-HDMI-A-4
[83.754146] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.491s)
[83.785108] Starting dynamic subtest: pipe-D-HDMI-A-4
[84.348105] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (0.572s)
[84.349621] Subtest coverage-7efc: SUCCESS (1.364s)
[84.557907] [009/156] (945s left) gem_mmap_gtt (fault-concurrent)
[84.609572] Subtest fault-concurrent: SKIP (0.000s)
[84.689818] [010/156] (945s left) api_intel_allocator (gem-pool)
[84.739793] Starting subtest: gem-pool
[84.762159] Subtest gem-pool: SUCCESS (0.024s)
[84.914427] [011/156] (944s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-fullscreen)
[86.411920] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-fullscreen
[86.415172] Subtest fbchdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[86.584677] [012/156] (943s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier)
[88.077492] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier
[88.080779] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier: SKIP (0.000s)
[88.212305] [013/156] (941s left) kms_ccs (crc-primary-rotation-180-yf-tiled-ccs)
[89.719232] Starting subtest: crc-primary-rotation-180-yf-tiled-ccs
[89.720754] Starting dynamic subtest: pipe-A-HDMI-A-4
[89.735712] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.001s)
[89.746344] Starting dynamic subtest: pipe-B-HDMI-A-4
[89.762178] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.001s)
[89.772295] Starting dynamic subtest: pipe-C-HDMI-A-4
[89.791126] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.001s)
[89.801247] Starting dynamic subtest: pipe-D-HDMI-A-4
[89.818970] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.001s)
[89.822361] Subtest crc-primary-rotation-180-yf-tiled-ccs: SKIP (0.008s)
[89.986602] [014/156] (940s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs)
[91.477802] Starting subtest: ccs-on-another-bo-y-tiled-gen12-mc-ccs
[91.479127] Starting dynamic subtest: pipe-A-HDMI-A-4
[91.492501] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.000s)
[91.503552] Starting dynamic subtest: pipe-B-HDMI-A-4
[91.517736] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.000s)
[91.527630] Starting dynamic subtest: pipe-C-HDMI-A-4
[91.541326] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.000s)
[91.551295] Starting dynamic subtest: pipe-D-HDMI-A-4
[91.566236] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.000s)
[91.569514] Subtest ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[91.754172] [015/156] (938s left) kms_frontbuffer_tracking (hdr-rgb565-draw-blt)
[93.254374] Starting subtest: hdr-rgb565-draw-blt
[93.257613] Subtest hdr-rgb565-draw-blt: SKIP (0.000s)
[93.425416] [016/156] (936s left) kms_pm_rpm (legacy-planes-dpms)
[96.985415] Starting subtest: legacy-planes-dpms
[96.988377] Starting dynamic subtest: plane-65
[103.930451] Dynamic subtest plane-65: SUCCESS (6.947s)
[103.980679] Starting dynamic subtest: plane-95
[110.882578] Dynamic subtest plane-95: SUCCESS (6.950s)
[110.934372] Starting dynamic subtest: plane-125
[117.978771] Dynamic subtest plane-125: SUCCESS (7.094s)
[118.029298] Starting dynamic subtest: plane-135
[124.941231] Dynamic subtest plane-135: SUCCESS (6.961s)
[124.990483] Starting dynamic subtest: plane-145
[131.876809] Dynamic subtest plane-145: SUCCESS (6.932s)
[131.927957] Starting dynamic subtest: plane-155
[138.839373] Dynamic subtest plane-155: SUCCESS (6.962s)
[138.842968] Subtest legacy-planes-dpms: SUCCESS (41.859s)
[139.860585] [017/156] (890s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[139.918229] Starting subtest: x-tiled-to-vebox-y-tiled
[139.919740] Starting dynamic subtest: smem
[139.999661] Dynamic subtest smem: SUCCESS (0.081s)
[140.010265] Starting dynamic subtest: lmem0
[140.755729] Dynamic subtest lmem0: SUCCESS (0.752s)
[140.757432] Subtest x-tiled-to-vebox-y-tiled: SUCCESS (0.834s)
[141.006820] [018/156] (889s left) sysfs_heartbeat_interval (invalid)
[141.059509] Starting subtest: invalid
[141.061107] Starting dynamic subtest: vecs0
[141.062726] Dynamic subtest vecs0: SUCCESS (0.001s)
[141.073230] Starting dynamic subtest: vcs0
[141.074826] Dynamic subtest vcs0: SUCCESS (0.000s)
[141.085688] Starting dynamic subtest: bcs0
[141.087431] Dynamic subtest bcs0: SUCCESS (0.000s)
[141.097959] Starting dynamic subtest: rcs0
[141.099860] Dynamic subtest rcs0: SUCCESS (0.000s)
[141.111722] Starting dynamic subtest: vcs1
[141.113266] Dynamic subtest vcs1: SUCCESS (0.000s)
[141.114971] Subtest invalid: SUCCESS (0.005s)
[141.173374] [019/156] (889s left) kms_frontbuffer_tracking (fbc-stridechange)
[142.671974] Starting subtest: fbc-stridechange
[144.644710] Subtest fbc-stridechange: SUCCESS (1.975s)
[144.835069] [020/156] (885s left) gem_ctx_param (basic-default)
[144.886833] Starting subtest: basic-default
[144.888790] Subtest basic-default: SUCCESS (0.000s)
[144.992395] [021/156] (885s left) kms_psr (fbc-pr-primary-mmap-gtt)
[146.486248] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s)
[146.552581] [022/156] (884s left) i915_pm_freq_mult (media-freq)
[146.600978] Starting subtest: media-freq
[146.602345] Starting dynamic subtest: gt0
[146.605230] Dynamic subtest gt0: SKIP (0.000s)
[146.608201] Subtest media-freq: SKIP (0.001s)
[146.725542] [023/156] (884s left) prime_busy (before-wait)
[146.777796] Starting subtest: before-wait
[146.784290] Starting dynamic subtest: rcs0
[146.821363] Dynamic subtest rcs0: SUCCESS (0.037s)
[146.832594] Starting dynamic subtest: bcs0
[146.869884] Dynamic subtest bcs0: SUCCESS (0.048s)
[146.882188] Starting dynamic subtest: vcs0
[146.911339] Dynamic subtest vcs0: SUCCESS (0.041s)
[146.929784] Starting dynamic subtest: vcs1
[146.954399] Dynamic subtest vcs1: SUCCESS (0.042s)
[146.972697] Starting dynamic subtest: vecs0
[146.996980] Dynamic subtest vecs0: SUCCESS (0.042s)
[147.123136] Subtest before-wait: SUCCESS (0.341s)
[147.322685] [024/156] (883s left) gem_exec_gttfill (engines)
[147.384356] Starting subtest: engines
[147.385884] Starting dynamic subtest: rcs0
[174.980788] Dynamic subtest rcs0: SUCCESS (27.595s)
[174.992524] Starting dynamic subtest: bcs0
[202.748695] Dynamic subtest bcs0: SUCCESS (27.767s)
[202.760122] Starting dynamic subtest: vcs0
[230.611502] Dynamic subtest vcs0: SUCCESS (27.862s)
[230.623041] Starting dynamic subtest: vcs1
[258.426592] Dynamic subtest vcs1: SUCCESS (27.814s)
[258.438350] Starting dynamic subtest: vecs0
[286.263984] Dynamic subtest vecs0: SUCCESS (27.837s)
[286.265409] Subtest engines: SUCCESS (138.879s)
[286.528676] [025/156] (744s left) syncobj_timeline (etime-multi-wait-all-submitted)
[286.634419] Starting subtest: etime-multi-wait-all-submitted
[286.739667] Subtest etime-multi-wait-all-submitted: SUCCESS (0.104s)
[286.838319] [026/156] (744s left) gem_ctx_param (get-priority-new-ctx)
[286.886651] Starting subtest: get-priority-new-ctx
[286.888136] Subtest get-priority-new-ctx: SUCCESS (0.001s)
[286.989191] [027/156] (744s left) kms_big_fb (linear-32bpp-rotate-90)
[288.508693] Starting subtest: linear-32bpp-rotate-90
[288.615276] Subtest linear-32bpp-rotate-90: SKIP (0.105s)
[288.758294] [028/156] (742s left) gem_lmem_swapping (heavy-verify-multi-ccs)
[293.177999] Starting subtest: heavy-verify-multi-ccs
[293.180383] Starting dynamic subtest: lmem0
[293.184174] Dynamic subtest lmem0: SKIP (0.000s)
[293.190176] Subtest heavy-verify-multi-ccs: SKIP (0.001s)
[297.076408] [FACT gem_lmem_swapping (heavy-verify-multi-ccs)] deleted: kernel.kmod_is_loaded.i915: true
[297.090200] [029/156] (734s left) gem_ctx_shared (disjoint-timelines)
[300.516684] Starting subtest: disjoint-timelines
[300.590790] Subtest disjoint-timelines: SUCCESS (0.075s)
[300.917192] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.i915: true
[300.917908] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.vgem: true
[300.920680] [030/156] (730s left) kms_psr (fbc-psr2-cursor-plane-move)
[302.415499] Subtest fbc-psr2-cursor-plane-move: SKIP (0.000s)
[302.488610] [031/156] (728s left) gem_bad_reloc (negative-reloc-bltcopy)
[302.546577] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[302.639435] [032/156] (728s left) kms_chamelium_hpd (hdmi-hpd)
[304.172119] Subtest hdmi-hpd: SKIP (0.000s)
[304.304115] [033/156] (727s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-fully-sf)
[305.801708] Subtest pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[305.881083] [034/156] (725s left) gem_eio (wait-10ms)
[306.019618] Starting subtest: wait-10ms
[306.395566] Subtest wait-10ms: SUCCESS (0.377s)
[307.964365] [035/156] (723s left) kms_content_protection (legacy-hdcp14)
[309.535364] Starting subtest: legacy-hdcp14
[309.544047] Subtest legacy-hdcp14: SKIP (0.002s)
[309.726987] [036/156] (722s left) syncobj_wait (reset-signaled)
[309.809317] Starting subtest: reset-signaled
[309.810817] Subtest reset-signaled: SUCCESS (0.000s)
[309.919630] [037/156] (721s left) gem_ctx_isolation (dirty-switch)
[309.979369] Starting subtest: dirty-switch
[309.980899] Starting dynamic subtest: rcs0
[310.788808] Dynamic subtest rcs0: SUCCESS (0.810s)
[310.802520] Starting dynamic subtest: bcs0
[311.647207] Dynamic subtest bcs0: SUCCESS (0.858s)
[311.659959] Starting dynamic subtest: vcs0
[312.477554] Dynamic subtest vcs0: SUCCESS (0.829s)
[312.490842] Starting dynamic subtest: vcs1
[313.316441] Dynamic subtest vcs1: SUCCESS (0.839s)
[313.329105] Starting dynamic subtest: vecs0
[314.161297] Dynamic subtest vecs0: SUCCESS (0.844s)
[314.162823] Subtest dirty-switch: SUCCESS (4.184s)
[314.462310] [038/156] (717s left) gem_flink_basic (bad-open)
[314.511753] Starting subtest: bad-open
[314.513528] Subtest bad-open: SUCCESS (0.000s)
[314.641556] [039/156] (717s left) gem_caching (writes)
[314.694472] Starting subtest: writes
[319.963085] Subtest writes: SUCCESS (5.269s)
[320.121680] [040/156] (712s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[321.635707] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[321.637154] Starting dynamic subtest: pipe-A
[322.418102] Dynamic subtest pipe-A: SUCCESS (0.784s)
[322.431571] Starting dynamic subtest: pipe-B
[323.191675] Dynamic subtest pipe-B: SUCCESS (0.773s)
[323.209521] Starting dynamic subtest: pipe-C
[323.964555] Dynamic subtest pipe-C: SUCCESS (0.772s)
[323.978656] Starting dynamic subtest: pipe-D
[324.739230] Dynamic subtest pipe-D: SUCCESS (0.773s)
[324.741263] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (3.106s)
[324.933867] [041/156] (707s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[324.985411] Starting subtest: single-wait-all-for-submit-available-submitted
[324.987050] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[325.114731] [042/156] (707s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-render)
[326.613658] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-render
[326.616449] Subtest fbchdr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[326.786263] [043/156] (705s left) kms_pm_rpm (system-suspend-idle)
[331.046634] Starting subtest: system-suspend-idle
[349.934142] Subtest system-suspend-idle: SUCCESS (4.460s)
[354.045847] [044/156] (681s left) kms_atomic_transition (modeset-transition)
[355.569172] Starting subtest: modeset-transition
[355.570763] Starting dynamic subtest: 1x-outputs
[357.706390] Dynamic subtest 1x-outputs: SUCCESS (2.135s)
[357.707998] Subtest modeset-transition: SUCCESS (2.140s)
[357.924669] [045/156] (677s left) kms_frontbuffer_tracking (pipe-fbc-rte)
[359.424373] Starting subtest: pipe-fbc-rte
[359.435229] Subtest pipe-fbc-rte: SKIP (0.001s)
[359.605660] [046/156] (676s left) gem_spin_batch (resubmit-all)
[359.665801] Starting subtest: resubmit-all
[359.667180] Starting dynamic subtest: rcs0
[359.689192] Dynamic subtest rcs0: SUCCESS (0.023s)
[359.704331] Starting dynamic subtest: bcs0
[359.705777] Dynamic subtest bcs0: SUCCESS (0.002s)
[359.720164] Starting dynamic subtest: vcs0
[359.721754] Dynamic subtest vcs0: SUCCESS (0.002s)
[359.735564] Starting dynamic subtest: vcs1
[359.736919] Dynamic subtest vcs1: SUCCESS (0.003s)
[359.751701] Starting dynamic subtest: vecs0
[359.753271] Dynamic subtest vecs0: SUCCESS (0.002s)
[359.754882] Subtest resubmit-all: SUCCESS (0.038s)
[360.083452] [047/156] (675s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff)
[361.581306] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff
[361.585110] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[361.788989] [048/156] (674s left) syncobj_basic (illegal-fd-to-handle)
[361.864903] Starting subtest: illegal-fd-to-handle
[361.866640] Subtest illegal-fd-to-handle: SUCCESS (0.000s)
[361.992562] [049/156] (674s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite)
[363.489033] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite
[363.491706] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[363.668964] [050/156] (672s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[365.180915] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[366.455891] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.272s)
[366.648665] [051/156] (669s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[366.697832] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[366.806744] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.106s)
[366.956939] [052/156] (669s left) api_intel_bb (misplaced-blitter)
[367.004868] Starting subtest: misplaced-blitter
[367.025461] Subtest misplaced-blitter: SUCCESS (0.021s)
[367.207510] [053/156] (669s left) kms_atomic_interruptible (universal-setplane-cursor)
[368.699770] Starting subtest: universal-setplane-cursor
[368.701435] Starting dynamic subtest: pipe-A-HDMI-A-4
[374.915867] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (6.216s)
[374.917361] Subtest universal-setplane-cursor: SUCCESS (6.219s)
[375.099067] [054/156] (661s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[376.609964] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[376.612963] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[376.773342] [055/156] (659s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[378.275237] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[378.277984] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[378.433113] [056/156] (658s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[379.946813] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[379.948515] Starting dynamic subtest: pipe-A-valid-mode
[381.278511] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.333s)
[381.279850] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SUCCESS (1.335s)
[381.477653] [057/156] (655s left) gem_pxp (create-protected-buffer)
[384.051331] Starting subtest: create-protected-buffer
[384.054566] Subtest create-protected-buffer: SKIP (0.000s)
[384.182945] [058/156] (652s left) gem_fence_thrash (bo-write-verify-threaded-x)
[384.229656] Starting subtest: bo-write-verify-threaded-x
[384.235087] Subtest bo-write-verify-threaded-x: SKIP (0.002s)
[384.315223] [059/156] (652s left) gen9_exec_parse (allowed-single)
[384.371215] Subtest allowed-single: SKIP (0.000s)
[384.448728] [060/156] (652s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[385.947219] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[385.952677] Subtest psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[386.140563] [061/156] (651s left) gem_exec_balancer (bonded-semaphore)
[386.203199] Starting subtest: bonded-semaphore
[386.206703] Subtest bonded-semaphore: SKIP (0.000s)
[386.688387] [062/156] (650s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping)
[388.181560] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping
[388.216664] Starting dynamic subtest: pipe-A-plane-0
[388.732999] Dynamic subtest pipe-A-plane-0: SUCCESS (0.516s)
[388.778223] Starting dynamic subtest: pipe-A-plane-3
[389.066092] Dynamic subtest pipe-A-plane-3: SUCCESS (0.330s)
[389.115686] Starting dynamic subtest: pipe-A-plane-5
[389.382778] Dynamic subtest pipe-A-plane-5: SUCCESS (0.314s)
[389.581601] Starting dynamic subtest: pipe-B-plane-0
[389.968251] Dynamic subtest pipe-B-plane-0: SUCCESS (0.399s)
[389.987267] Starting dynamic subtest: pipe-B-plane-3
[390.318123] Dynamic subtest pipe-B-plane-3: SUCCESS (0.346s)
[390.370799] Starting dynamic subtest: pipe-B-plane-5
[390.634254] Dynamic subtest pipe-B-plane-5: SUCCESS (0.313s)
[390.707475] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping: SUCCESS (2.527s)
[390.920317] [063/156] (646s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc)
[392.419914] Starting subtest: fbc-rgb565-draw-mmap-wc
[392.422634] Subtest fbc-rgb565-draw-mmap-wc: SKIP (0.000s)
[392.611376] [064/156] (644s left) kms_pwrite_crc (basic)
[394.102199] Starting subtest: basic
[394.746966] Subtest basic: SUCCESS (0.644s)
[394.957671] [065/156] (642s left) gem_flink_basic (bad-flink)
[395.005232] Starting subtest: bad-flink
[395.006888] Subtest bad-flink: SUCCESS (0.000s)
[395.118203] [066/156] (642s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-move)
[396.618526] Starting subtest: hdr-2p-primscrn-spr-indfb-move
[396.621569] Subtest hdr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[396.830359] [067/156] (640s left) gem_ctx_persistence (legacy-engines-persistence)
[396.887165] Starting subtest: legacy-engines-persistence
[396.888704] Starting dynamic subtest: render
[397.705801] Dynamic subtest render: SUCCESS (0.817s)
[397.723207] Starting dynamic subtest: bsd1
[398.459837] Dynamic subtest bsd1: SUCCESS (0.752s)
[398.479184] Starting dynamic subtest: bsd2
[399.233867] Dynamic subtest bsd2: SUCCESS (0.772s)
[399.251372] Starting dynamic subtest: blt
[399.994923] Dynamic subtest blt: SUCCESS (0.759s)
[400.012343] Starting dynamic subtest: vebox
[400.765934] Dynamic subtest vebox: SUCCESS (0.769s)
[400.767743] Subtest legacy-engines-persistence: SUCCESS (3.880s)
[400.923372] [068/156] (636s left) gen7_exec_parse (basic-allowed)
[400.978532] Subtest basic-allowed: SKIP (0.000s)
[401.061147] [069/156] (636s left) gem_exec_schedule (reorder-wide)
[401.285552] Subtest reorder-wide: SKIP (0.000s)
[401.525902] [070/156] (636s left) kms_frontbuffer_tracking (psrhdr-slowdraw)
[403.025757] Starting subtest: psrhdr-slowdraw
[403.029265] Subtest psrhdr-slowdraw: SKIP (0.000s)
[403.219375] [071/156] (634s left) perf (non-zero-reason)
[403.366245] Starting subtest: non-zero-reason
[403.367579] Starting dynamic subtest: 0-rcs0
[414.491879] Dynamic subtest 0-rcs0: SUCCESS (11.123s)
[414.493364] Subtest non-zero-reason: SUCCESS (11.124s)
[414.563998] [072/156] (623s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-pwrite)
[416.062606] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-pwrite
[416.065889] Subtest fbchdr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[416.246485] [073/156] (622s left) i915_drm_fdinfo (all-busy-check-all)
[416.301006] Starting subtest: all-busy-check-all
[416.320780] Subtest all-busy-check-all: SKIP (0.020s)
[416.430663] [074/156] (621s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[417.968981] Starting subtest: psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[417.972657] Subtest psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[418.148377] [075/156] (620s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[419.645525] Starting subtest: compare-crc-sanitycheck-nv12
[419.647054] Starting dynamic subtest: pipe-A-HDMI-A-4
[420.285075] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.640s)
[420.303678] Starting dynamic subtest: pipe-B-HDMI-A-4
[420.815429] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (0.527s)
[420.834585] Starting dynamic subtest: pipe-C-HDMI-A-4
[421.306457] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (0.488s)
[421.321767] Starting dynamic subtest: pipe-D-HDMI-A-4
[421.812876] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (0.504s)
[421.814626] Subtest compare-crc-sanitycheck-nv12: SUCCESS (2.170s)
[422.020640] [076/156] (616s left) gem_exec_reloc (basic-write-gtt)
[422.076016] Subtest basic-write-gtt: SKIP (0.000s)
[422.174851] [077/156] (616s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[423.980225] Starting subtest: cursor-rapid-movement-max-size
[423.984744] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[424.168327] [078/156] (614s left) kms_color_pipeline (plane-lut1d)
[425.657694] Starting subtest: plane-lut1d
[425.859682] Starting dynamic subtest: pipe-A-plane-0
[426.108018] Dynamic subtest pipe-A-plane-0: SUCCESS (0.248s)
[426.155519] Starting dynamic subtest: pipe-A-plane-1
[426.308135] Dynamic subtest pipe-A-plane-1: SUCCESS (0.193s)
[426.352370] Starting dynamic subtest: pipe-A-plane-2
[426.522984] Dynamic subtest pipe-A-plane-2: SUCCESS (0.209s)
[426.830103] Starting dynamic subtest: pipe-B-plane-0
[426.987229] Dynamic subtest pipe-B-plane-0: SUCCESS (0.197s)
[427.029004] Starting dynamic subtest: pipe-B-plane-1
[427.188015] Dynamic subtest pipe-B-plane-1: SUCCESS (0.193s)
[427.237974] Starting dynamic subtest: pipe-B-plane-2
[427.387010] Dynamic subtest pipe-B-plane-2: SUCCESS (0.193s)
[427.695815] Starting dynamic subtest: pipe-C-plane-0
[427.849745] Dynamic subtest pipe-C-plane-0: SUCCESS (0.180s)
[427.889404] Starting dynamic subtest: pipe-C-plane-1
[428.066157] Dynamic subtest pipe-C-plane-1: SUCCESS (0.209s)
[428.111634] Starting dynamic subtest: pipe-C-plane-2
[428.282797] Dynamic subtest pipe-C-plane-2: SUCCESS (0.209s)
[428.607982] Starting dynamic subtest: pipe-D-plane-0
[428.773012] Dynamic subtest pipe-D-plane-0: SUCCESS (0.197s)
[428.816969] Starting dynamic subtest: pipe-D-plane-1
[428.989890] Dynamic subtest pipe-D-plane-1: SUCCESS (0.208s)
[429.032541] Starting dynamic subtest: pipe-D-plane-2
[429.206545] Dynamic subtest pipe-D-plane-2: SUCCESS (0.209s)
[429.261213] Subtest plane-lut1d: SUCCESS (3.602s)
[429.470916] [079/156] (609s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[430.982544] Starting subtest: yf-tiled-16bpp-rotate-180
[430.986034] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[431.178677] [080/156] (607s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[432.689979] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[432.693054] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[432.869198] [081/156] (606s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[434.375101] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[434.378957] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[434.525256] [082/156] (604s left) gem_exec_reloc (basic-wc-read)
[434.580621] Subtest basic-wc-read: SKIP (0.000s)
[434.695192] [083/156] (604s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[436.189791] Starting subtest: short-busy-flip-before-cursor-toggle
[436.192500] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[436.362728] [084/156] (603s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[437.858906] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[437.877935] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.017s)
[438.006941] [085/156] (601s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[439.511612] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[439.515171] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[439.690308] [086/156] (599s left) kms_draw_crc (draw-method-render)
[441.183171] Starting subtest: draw-method-render
[441.184918] Starting dynamic subtest: xrgb8888-untiled
[441.589399] Dynamic subtest xrgb8888-untiled: SUCCESS (0.406s)
[441.641954] Starting dynamic subtest: rgb565-untiled
[441.954662] Dynamic subtest rgb565-untiled: SUCCESS (0.359s)
[441.995872] Starting dynamic subtest: xrgb2101010-untiled
[442.289375] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.331s)
[442.341265] Starting dynamic subtest: xbgr16161616f-untiled
[442.671123] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.375s)
[442.689864] Starting dynamic subtest: xrgb8888-xtiled
[442.839456] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.165s)
[442.880393] Starting dynamic subtest: rgb565-xtiled
[443.022839] Dynamic subtest rgb565-xtiled: SUCCESS (0.176s)
[443.072117] Starting dynamic subtest: xrgb2101010-xtiled
[443.206115] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.176s)
[443.256892] Starting dynamic subtest: xbgr16161616f-xtiled
[443.406217] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.192s)
[443.425365] Starting dynamic subtest: xrgb8888-ytiled
[443.572452] Dynamic subtest xrgb8888-ytiled: SUCCESS (0.161s)
[443.622326] Starting dynamic subtest: rgb565-ytiled
[443.772511] Dynamic subtest rgb565-ytiled: SUCCESS (0.192s)
[443.822357] Starting dynamic subtest: xrgb2101010-ytiled
[443.955985] Dynamic subtest xrgb2101010-ytiled: SUCCESS (0.176s)
[444.006949] Starting dynamic subtest: xbgr16161616f-ytiled
[444.155981] Dynamic subtest xbgr16161616f-ytiled: SUCCESS (0.192s)
[444.165594] Subtest draw-method-render: SUCCESS (2.979s)
[444.417132] [087/156] (595s left) kms_cursor_crc (cursor-sliding-256x85)
[446.146746] Starting subtest: cursor-sliding-256x85
[446.152948] Starting dynamic subtest: pipe-A-HDMI-A-4
[450.544655] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (4.391s)
[450.581958] Starting dynamic subtest: pipe-D-HDMI-A-4
[454.901671] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (4.334s)
[454.903274] Subtest cursor-sliding-256x85: SUCCESS (8.756s)
[455.243509] [088/156] (584s left) kms_cursor_crc (cursor-onscreen-256x256)
[456.958592] Starting subtest: cursor-onscreen-256x256
[456.965811] Starting dynamic subtest: pipe-A-HDMI-A-4
[459.630795] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (2.664s)
[459.667467] Starting dynamic subtest: pipe-D-HDMI-A-4
[462.303229] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (2.650s)
[462.304735] Subtest cursor-onscreen-256x256: SUCCESS (5.344s)
[462.651122] [089/156] (577s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render)
[464.150297] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render
[464.153163] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[464.344236] [090/156] (575s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[465.843668] Starting subtest: fbcpsr-slowdraw
[465.846623] Subtest fbcpsr-slowdraw: SKIP (0.000s)
[466.022674] [091/156] (574s left) kms_lease (cursor-implicit-plane)
[467.513435] Starting subtest: cursor-implicit-plane
[467.515252] Starting dynamic subtest: pipe-A-HDMI-A-4
[467.926440] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.411s)
[467.944002] Starting dynamic subtest: pipe-B-HDMI-A-4
[468.156667] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (0.228s)
[468.176595] Starting dynamic subtest: pipe-C-HDMI-A-4
[468.381977] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (0.222s)
[468.401826] Starting dynamic subtest: pipe-D-HDMI-A-4
[468.620413] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (0.233s)
[468.623459] Subtest cursor-implicit-plane: SUCCESS (1.106s)
[468.837897] [092/156] (571s left) syncobj_wait (invalid-reset-zero-handles)
[468.888990] Starting subtest: invalid-reset-zero-handles
[468.890503] Subtest invalid-reset-zero-handles: SUCCESS (0.000s)
[469.034360] [093/156] (571s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render)
[470.534175] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render
[470.537644] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[470.736844] [094/156] (569s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[472.246419] Starting subtest: yf-tiled-64bpp-rotate-90
[472.250169] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[472.410187] [095/156] (568s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc)
[473.911147] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc
[473.914333] Subtest fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[474.088870] [096/156] (566s left) gem_exec_fence (long-history)
[474.522037] Starting subtest: long-history
[480.593251] Subtest long-history: SUCCESS (6.069s)
[481.004396] [097/156] (559s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt)
[482.502295] Starting subtest: psr-1p-primscrn-indfb-msflip-blt
[482.504896] Subtest psr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[482.651852] [098/156] (558s left) kms_ccs (bad-rotation-90-yf-tiled-ccs)
[484.148970] Starting subtest: bad-rotation-90-yf-tiled-ccs
[484.150691] Starting dynamic subtest: pipe-A-HDMI-A-4
[484.167267] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.000s)
[484.182746] Starting dynamic subtest: pipe-B-HDMI-A-4
[484.199813] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.000s)
[484.214444] Starting dynamic subtest: pipe-C-HDMI-A-4
[484.241809] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.000s)
[484.256030] Starting dynamic subtest: pipe-D-HDMI-A-4
[484.269141] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.000s)
[484.271768] Subtest bad-rotation-90-yf-tiled-ccs: SKIP (0.010s)
[484.423712] [099/156] (556s left) kms_cursor_legacy (long-nonblocking-modeset-vs-cursor-atomic)
[485.914289] Starting subtest: long-nonblocking-modeset-vs-cursor-atomic
[487.891321] Subtest long-nonblocking-modeset-vs-cursor-atomic: SUCCESS (1.976s)
[488.089851] [100/156] (552s left) kms_chamelium_audio (dp-audio-edid)
[489.639827] Subtest dp-audio-edid: SKIP (0.000s)
[489.795571] [101/156] (551s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[491.289088] Starting subtest: addfb25-yf-tiled-legacy
[491.290842] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[491.505697] [102/156] (549s left) kms_frontbuffer_tracking (hdr-1p-primscrn-indfb-pgflip-blt)
[493.005309] Starting subtest: hdr-1p-primscrn-indfb-pgflip-blt
[493.008607] Subtest hdr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[493.202860] [103/156] (548s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff)
[494.734447] Starting subtest: psr-2p-primscrn-cur-indfb-onoff
[494.737806] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[494.894386] [104/156] (546s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw)
[496.396539] Starting subtest: fbc-2p-pri-indfb-multidraw
[496.399636] Subtest fbc-2p-pri-indfb-multidraw: SKIP (0.000s)
[496.573208] [105/156] (545s left) kms_sequence (queue-busy)
[498.067715] Starting subtest: queue-busy
[498.069210] Starting dynamic subtest: pipe-A-HDMI-A-4
[500.646955] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (2.579s)
[500.662904] Starting dynamic subtest: pipe-B-HDMI-A-4
[503.071373] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (2.422s)
[503.089617] Starting dynamic subtest: pipe-C-HDMI-A-4
[505.500170] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (2.426s)
[505.518229] Starting dynamic subtest: pipe-D-HDMI-A-4
[507.935848] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (2.433s)
[507.937431] Subtest queue-busy: SUCCESS (9.871s)
[508.155743] [106/156] (533s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[509.645047] Starting subtest: plane-upscale-20x20-with-rotation
[509.646763] Starting dynamic subtest: pipe-A
[510.086410] Dynamic subtest pipe-A: SKIP (0.433s)
[510.101562] Starting dynamic subtest: pipe-B
[510.502103] Dynamic subtest pipe-B: SKIP (0.418s)
[510.517027] Starting dynamic subtest: pipe-C
[510.924510] Dynamic subtest pipe-C: SKIP (0.422s)
[510.939526] Starting dynamic subtest: pipe-D
[511.349037] Dynamic subtest pipe-D: SKIP (0.421s)
[511.351991] Subtest plane-upscale-20x20-with-rotation: SKIP (1.700s)
[511.552031] [107/156] (530s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-pwrite)
[513.059016] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-pwrite
[513.062602] Subtest psrhdr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[513.204377] [108/156] (528s left) gem_exec_reloc (basic-write-wc)
[513.260256] Subtest basic-write-wc: SKIP (0.000s)
[513.366764] [109/156] (528s left) syncobj_timeline (reset-signaled)
[513.415863] Starting subtest: reset-signaled
[513.417288] Subtest reset-signaled: SUCCESS (0.000s)
[513.532332] [110/156] (528s left) kms_psr (pr-cursor-plane-move)
[515.023538] Subtest pr-cursor-plane-move: SKIP (0.000s)
[515.112834] [111/156] (526s left) syncobj_wait (invalid-wait-illegal-handle)
[515.162281] Starting subtest: invalid-wait-illegal-handle
[515.164290] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[515.304669] [112/156] (526s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[516.804212] Starting subtest: extended-pageflip-modeset-hang-oldfb
[516.805818] Starting dynamic subtest: pipe-A
[524.534198] Dynamic subtest pipe-A: SUCCESS (7.731s)
[524.553164] Starting dynamic subtest: pipe-D
[532.266401] Dynamic subtest pipe-D: SUCCESS (7.727s)
[532.267818] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (15.467s)
[532.509325] [113/156] (509s left) kms_psr (psr2-primary-blt)
[534.001544] Subtest psr2-primary-blt: SKIP (0.000s)
[534.092813] [114/156] (508s left) gem_softpin (evict-snoop-interruptible)
[534.154253] Starting subtest: evict-snoop-interruptible
[534.157923] Subtest evict-snoop-interruptible: SKIP (0.000s)
[534.265769] [115/156] (508s left) kms_force_connector_basic (force-load-detect)
[534.399900] Starting subtest: force-load-detect
[534.403360] Subtest force-load-detect: SKIP (0.000s)
[535.317792] [116/156] (507s left) kms_chamelium_edid (hdmi-mode-timings)
[536.854994] Subtest hdmi-mode-timings: SKIP (0.000s)
[536.987129] [117/156] (505s left) syncobj_timeline (invalid-signal-zero-handles)
[537.038394] Starting subtest: invalid-signal-zero-handles
[537.042695] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[537.172700] [118/156] (505s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt)
[538.668400] Starting subtest: fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt
[538.671044] Subtest fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[538.855285] [119/156] (503s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[540.357167] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[540.361206] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[540.536398] [120/156] (502s left) gem_mmap_gtt (fault-concurrent-x)
[540.603383] Subtest fault-concurrent-X: SKIP (0.000s)
[540.705304] [121/156] (502s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[540.760413] Starting subtest: y-tiled-to-vebox-y-tiled
[540.761898] Starting dynamic subtest: smem
[540.846028] Dynamic subtest smem: SUCCESS (0.085s)
[540.861542] Starting dynamic subtest: lmem0
[541.595554] Dynamic subtest lmem0: SUCCESS (0.744s)
[541.597301] Subtest y-tiled-to-vebox-y-tiled: SUCCESS (0.832s)
[541.861281] [122/156] (501s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-shrfb-plflip-blt)
[543.357818] Starting subtest: psrhdr-2p-scndscrn-shrfb-plflip-blt
[543.360564] Subtest psrhdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[543.521184] [123/156] (499s left) vgem_basic (debugfs)
[543.584759] Starting subtest: debugfs
[543.586295] Subtest debugfs: SUCCESS (0.000s)
[543.692781] [124/156] (499s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[545.182919] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[545.184388] Starting dynamic subtest: pipe-A-valid-mode
[546.464710] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.281s)
[546.466038] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SUCCESS (1.283s)
[546.682665] [125/156] (496s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite)
[548.192466] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite
[548.196029] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[548.379485] [126/156] (495s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[549.878424] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[549.881025] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[550.040776] [127/156] (493s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt)
[551.540489] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt
[551.543791] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[551.714895] [128/156] (491s left) kms_vblank (wait-forked-hang)
[553.203974] Starting subtest: wait-forked-hang
[553.208190] Starting dynamic subtest: pipe-A-HDMI-A-4
[579.889915] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (26.683s)
[579.908577] Starting dynamic subtest: pipe-D-HDMI-A-4
[604.909901] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (25.016s)
[604.911316] Subtest wait-forked-hang: SUCCESS (51.707s)
[605.119771] [129/156] (438s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[606.608200] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[606.609918] Starting dynamic subtest: pipe-A-valid-mode
[609.186611] Dynamic subtest pipe-A-valid-mode: SUCCESS (2.577s)
[609.187900] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SUCCESS (2.580s)
[609.390575] [130/156] (434s left) gem_ctx_exec (basic-invalid-context)
[609.442631] Starting subtest: basic-invalid-context
[609.446356] Subtest basic-invalid-context: SUCCESS (0.004s)
[609.609691] [131/156] (434s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-indfb-msflip-blt)
[611.108883] Starting subtest: fbcpsrhdr-1p-primscrn-indfb-msflip-blt
[611.111784] Subtest fbcpsrhdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[611.260525] [132/156] (432s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[612.756661] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (0.000s)
[612.847491] [133/156] (431s left) gem_ppgtt (blt-vs-render-ctxn)
[612.900860] Starting subtest: blt-vs-render-ctxN
[645.624706] Subtest blt-vs-render-ctxN: SUCCESS (32.720s)
[645.870688] [134/156] (398s left) gem_exec_reloc (basic-gtt-cpu-noreloc)
[645.982293] Subtest basic-gtt-cpu-noreloc: SKIP (0.000s)
[646.081670] [135/156] (398s left) gem_softpin (invalid)
[646.136211] Starting subtest: invalid
[646.149369] Subtest invalid: SUCCESS (0.013s)
[646.374484] [136/156] (398s left) gem_exec_capture (capture)
[646.434029] Starting subtest: capture
[646.435695] Starting dynamic subtest: rcs0-smem
[647.335022] Dynamic subtest rcs0-smem: SUCCESS (0.901s)
[647.352344] Starting dynamic subtest: rcs0-lmem0
[648.217827] Dynamic subtest rcs0-lmem0: SUCCESS (0.880s)
[648.233821] Starting dynamic subtest: bcs0-smem
[649.588369] Dynamic subtest bcs0-smem: SUCCESS (1.371s)
[649.604276] Starting dynamic subtest: bcs0-lmem0
[650.624178] Dynamic subtest bcs0-lmem0: SUCCESS (1.032s)
[650.640087] Starting dynamic subtest: vcs0-smem
[651.483249] Dynamic subtest vcs0-smem: SUCCESS (0.859s)
[651.499217] Starting dynamic subtest: vcs0-lmem0
[652.350221] Dynamic subtest vcs0-lmem0: SUCCESS (0.863s)
[652.365363] Starting dynamic subtest: vcs1-smem
[653.210861] Dynamic subtest vcs1-smem: SUCCESS (0.862s)
[653.226226] Starting dynamic subtest: vcs1-lmem0
[654.075788] Dynamic subtest vcs1-lmem0: SUCCESS (0.863s)
[654.091043] Starting dynamic subtest: vecs0-smem
[655.506887] Dynamic subtest vecs0-smem: FAIL (1.392s)
[655.521840] Starting dynamic subtest: vecs0-lmem0
[656.513413] Dynamic subtest vecs0-lmem0: FAIL (0.986s)
[656.514883] Subtest capture: FAIL (10.024s)
[656.608040] [137/156] (387s left) gem_tiled_blits (basic)
[656.661407] Subtest basic: SKIP (0.000s)
[656.750826] [138/156] (387s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt)
[658.272891] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt
[658.276228] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[658.446638] [139/156] (386s left) gem_mmap_offset (partial-remap)
[658.498000] Starting subtest: partial-remap
[658.499775] Starting dynamic subtest: lmem0
[658.512705] Dynamic subtest lmem0: SUCCESS (0.005s)
[658.528067] Starting dynamic subtest: smem0
[658.531264] Dynamic subtest smem0: SUCCESS (0.005s)
[658.532955] Subtest partial-remap: SUCCESS (0.013s)
[658.685503] [140/156] (386s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-blt)
[660.182386] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-blt
[660.184980] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s)
[660.367481] [141/156] (384s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-blt)
[661.866496] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-blt
[661.870046] Subtest hdr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[662.050007] [142/156] (382s left) i915_power (sanity)
[662.099018] Starting subtest: sanity
[666.140803] Subtest sanity: SUCCESS (4.035s)
[666.285042] [143/156] (378s left) gem_exec_schedule (preempt-queue)
[666.363986] Subtest preempt-queue: SKIP (0.000s)
[666.800383] [144/156] (378s left) kms_cursor_legacy (cursorb-vs-flipa-legacy)
[668.293980] Starting subtest: cursorB-vs-flipA-legacy
[668.296789] Subtest cursorB-vs-flipA-legacy: SKIP (0.002s)
[668.443764] [145/156] (376s left) i915_pm_rps (reset)
[668.502175] Starting subtest: reset
[673.232735] Subtest reset: SUCCESS (4.730s)
[673.449468] [146/156] (371s left) kms_psr (fbc-psr-cursor-plane-onoff)
[674.942416] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.000s)
[675.033551] [147/156] (370s left) kms_setmode (clone-exclusive-crtc)
[675.085386] Starting subtest: clone-exclusive-crtc
[675.090290] Subtest clone-exclusive-crtc: SKIP (0.000s)
[675.209479] [148/156] (370s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[676.707667] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[676.710439] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[676.911027] [149/156] (368s left) kms_flip (flip-vs-rmfb)
[678.402419] Starting subtest: flip-vs-rmfb
[678.409469] Starting dynamic subtest: A-HDMI-A4
[686.236286] Dynamic subtest A-HDMI-A4: SUCCESS (7.826s)
[686.288644] Starting dynamic subtest: B-HDMI-A4
[693.945788] Dynamic subtest B-HDMI-A4: SUCCESS (7.698s)
[693.985962] Starting dynamic subtest: C-HDMI-A4
[701.639610] Dynamic subtest C-HDMI-A4: SUCCESS (7.689s)
[701.685372] Starting dynamic subtest: D-HDMI-A4
[709.332368] Dynamic subtest D-HDMI-A4: SUCCESS (7.688s)
[709.334254] Subtest flip-vs-rmfb: SUCCESS (30.933s)
[709.601515] [150/156] (336s left) gem_exec_whisper (basic-contexts-all)
[709.663948] Starting subtest: basic-contexts-all
[716.766329] Subtest basic-contexts-all: SUCCESS (7.097s)
[717.033074] [151/156] (328s left) dmabuf_sync_file (export-basic)
[717.089660] Starting subtest: export-basic
[717.091213] Subtest export-basic: SUCCESS (0.001s)
[717.195307] [152/156] (328s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[718.692248] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[718.695187] Subtest hdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[718.901511] [153/156] (327s left) gem_sync (basic-each)
[718.968646] Starting subtest: basic-each
[721.342034] Subtest basic-each: SUCCESS (2.377s)
[721.683819] [154/156] (324s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc)
[723.173277] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc
[723.174987] Starting dynamic subtest: pipe-A-HDMI-A-4
[723.195320] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.000s)
[723.211442] Starting dynamic subtest: pipe-B-HDMI-A-4
[723.230300] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.000s)
[723.245979] Starting dynamic subtest: pipe-C-HDMI-A-4
[723.266935] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.000s)
[723.282650] Starting dynamic subtest: pipe-D-HDMI-A-4
[723.303064] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.000s)
[723.306669] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.010s)
[723.459056] [155/156] (322s left) syncobj_wait (multi-wait-for-submit-submitted)
[723.508361] Starting subtest: multi-wait-for-submit-submitted
[723.612898] Subtest multi-wait-for-submit-submitted: SUCCESS (0.104s)
[723.783905] [156/156] (322s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[725.284262] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt
[725.287864] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[725.463884] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: kms_pipe_b_c_ivb
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_force_connector_basic
results: parsing output: 5/ for test: perf_pmu
results: parsing output: 6/ for test: gem_ccs
results: parsing output: 7/ for test: kms_plane_alpha_blend
results: parsing output: 8/ for test: gem_mmap_gtt
results: parsing output: 9/ for test: api_intel_allocator
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: kms_plane
results: parsing output: 12/ for test: kms_ccs
results: parsing output: 13/ for test: kms_ccs
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: kms_pm_rpm
results: parsing output: 16/ for test: gem_render_copy
results: parsing output: 17/ for test: sysfs_heartbeat_interval
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: gem_ctx_param
results: parsing output: 20/ for test: kms_psr
results: parsing output: 21/ for test: i915_pm_freq_mult
results: parsing output: 22/ for test: prime_busy
results: parsing output: 23/ for test: gem_exec_gttfill
results: parsing output: 24/ for test: syncobj_timeline
results: parsing output: 25/ for test: gem_ctx_param
results: parsing output: 26/ for test: kms_big_fb
results: parsing output: 27/ for test: gem_lmem_swapping
results: parsing output: 28/ for test: gem_ctx_shared
results: parsing output: 29/ for test: kms_psr
results: parsing output: 30/ for test: gem_bad_reloc
results: parsing output: 31/ for test: kms_chamelium_hpd
results: parsing output: 32/ for test: kms_psr2_sf
results: parsing output: 33/ for test: gem_eio
results: parsing output: 34/ for test: kms_content_protection
results: parsing output: 35/ for test: syncobj_wait
results: parsing output: 36/ for test: gem_ctx_isolation
results: parsing output: 37/ for test: gem_flink_basic
results: parsing output: 38/ for test: gem_caching
results: parsing output: 39/ for test: kms_plane_scaling
results: parsing output: 40/ for test: syncobj_timeline
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_pm_rpm
results: parsing output: 43/ for test: kms_atomic_transition
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: gem_spin_batch
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: syncobj_basic
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: kms_big_fb
results: parsing output: 50/ for test: syncobj_timeline
results: parsing output: 51/ for test: api_intel_bb
results: parsing output: 52/ for test: kms_atomic_interruptible
results: parsing output: 53/ for test: kms_big_fb
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: kms_flip_scaled_crc
results: parsing output: 56/ for test: gem_pxp
results: parsing output: 57/ for test: gem_fence_thrash
results: parsing output: 58/ for test: gen9_exec_parse
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: gem_exec_balancer
results: parsing output: 61/ for test: kms_plane
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: kms_pwrite_crc
results: parsing output: 64/ for test: gem_flink_basic
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: gem_ctx_persistence
results: parsing output: 67/ for test: gen7_exec_parse
results: parsing output: 68/ for test: gem_exec_schedule
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: perf
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: i915_drm_fdinfo
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: kms_pipe_crc_basic
results: parsing output: 75/ for test: gem_exec_reloc
results: parsing output: 76/ for test: kms_cursor_crc
results: parsing output: 77/ for test: kms_color_pipeline
results: parsing output: 78/ for test: kms_big_fb
results: parsing output: 79/ for test: kms_big_fb
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: gem_exec_reloc
results: parsing output: 82/ for test: kms_cursor_legacy
results: parsing output: 83/ for test: kms_flip
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: kms_draw_crc
results: parsing output: 86/ for test: kms_cursor_crc
results: parsing output: 87/ for test: kms_cursor_crc
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_lease
results: parsing output: 91/ for test: syncobj_wait
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: kms_big_fb
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: gem_exec_fence
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: kms_ccs
results: parsing output: 98/ for test: kms_cursor_legacy
results: parsing output: 99/ for test: kms_chamelium_audio
results: parsing output: 100/ for test: kms_addfb_basic
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: kms_sequence
results: parsing output: 105/ for test: kms_plane_scaling
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: gem_exec_reloc
results: parsing output: 108/ for test: syncobj_timeline
results: parsing output: 109/ for test: kms_psr
results: parsing output: 110/ for test: syncobj_wait
results: parsing output: 111/ for test: kms_busy
results: parsing output: 112/ for test: kms_psr
results: parsing output: 113/ for test: gem_softpin
results: parsing output: 114/ for test: kms_force_connector_basic
results: parsing output: 115/ for test: kms_chamelium_edid
results: parsing output: 116/ for test: syncobj_timeline
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: gem_mmap_gtt
results: parsing output: 120/ for test: gem_render_copy
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: vgem_basic
results: parsing output: 123/ for test: kms_flip_scaled_crc
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: kms_vblank
results: parsing output: 128/ for test: kms_flip_scaled_crc
results: parsing output: 129/ for test: gem_ctx_exec
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: kms_psr2_sf
results: parsing output: 132/ for test: gem_ppgtt
results: parsing output: 133/ for test: gem_exec_reloc
results: parsing output: 134/ for test: gem_softpin
results: parsing output: 135/ for test: gem_exec_capture
results: parsing output: 136/ for test: gem_tiled_blits
results: parsing output: 137/ for test: kms_frontbuffer_tracking
results: parsing output: 138/ for test: gem_mmap_offset
results: parsing output: 139/ for test: kms_frontbuffer_tracking
results: parsing output: 140/ for test: kms_frontbuffer_tracking
results: parsing output: 141/ for test: i915_power
results: parsing output: 142/ for test: gem_exec_schedule
results: parsing output: 143/ for test: kms_cursor_legacy
results: parsing output: 144/ for test: i915_pm_rps
results: parsing output: 145/ for test: kms_psr
results: parsing output: 146/ for test: kms_setmode
results: parsing output: 147/ for test: kms_frontbuffer_tracking
results: parsing output: 148/ for test: kms_flip
results: parsing output: 149/ for test: gem_exec_whisper
results: parsing output: 150/ for test: dmabuf_sync_file
results: parsing output: 151/ for test: kms_frontbuffer_tracking
results: parsing output: 152/ for test: gem_sync
results: parsing output: 153/ for test: kms_ccs
results: parsing output: 154/ for test: syncobj_wait
results: parsing output: 155/ for test: kms_frontbuffer_tracking
Done.