[66.363431] Initializing watchdogs
[66.363512]   /dev/watchdog0
[66.394495] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:03:00.0: 8086:4905 Intel Dg1 (Gen12) DG1 [Iris Xe MAX Graphics]
[66.409565] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[66.414461] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[66.417090] [001/154] (960s left) kms_frontbuffer_tracking (fbc-tiling-linear)
[67.914154] Starting subtest: fbc-tiling-linear
[70.445069] Subtest fbc-tiling-linear: SUCCESS (2.537s)
[70.658994] [002/154] (956s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes)
[70.706031] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s)
[70.779513] [003/154] (956s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[72.277989] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[72.280028] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[72.509183] [004/154] (954s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[74.002239] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[74.003869] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[74.160462] [005/154] (953s left) kms_force_connector_basic (prune-stale-modes)
[74.308202] Starting subtest: prune-stale-modes
[74.331521] Subtest prune-stale-modes: SUCCESS (0.023s)
[75.978685] [006/154] (951s left) perf_pmu (most-busy-check-all)
[76.035030] Starting subtest: most-busy-check-all
[76.035761] Starting dynamic subtest: rcs0
[76.600932] Dynamic subtest rcs0: SUCCESS (0.566s)
[76.611121] Starting dynamic subtest: bcs0
[77.147060] Dynamic subtest bcs0: SUCCESS (0.546s)
[77.158383] Starting dynamic subtest: vcs0
[77.696424] Dynamic subtest vcs0: SUCCESS (0.548s)
[77.710058] Starting dynamic subtest: vcs1
[78.248488] Dynamic subtest vcs1: SUCCESS (0.551s)
[78.261377] Starting dynamic subtest: vecs0
[78.800577] Dynamic subtest vecs0: SUCCESS (0.551s)
[78.802802] Subtest most-busy-check-all: SUCCESS (2.767s)
[78.917483] [007/154] (948s left) gem_ccs (large-ctrl-surf-copy)
[78.969834] Starting subtest: large-ctrl-surf-copy
[78.971759] Subtest large-ctrl-surf-copy: SKIP (0.000s)
[79.096012] [008/154] (948s left) kms_plane_alpha_blend (coverage-7efc)
[80.583861] Starting subtest: coverage-7efc
[80.866163] Starting dynamic subtest: pipe-A-HDMI-A-4
[81.355035] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.489s)
[81.387234] Starting dynamic subtest: pipe-D-HDMI-A-4
[81.982135] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (0.604s)
[81.982850] Subtest coverage-7efc: SUCCESS (1.399s)
[82.195828] [009/154] (945s left) gem_mmap_gtt (fault-concurrent)
[82.246081] Subtest fault-concurrent: SKIP (0.000s)
[82.322105] [010/154] (945s left) api_intel_allocator (gem-pool)
[82.367421] Starting subtest: gem-pool
[82.391683] Subtest gem-pool: SUCCESS (0.025s)
[82.597430] [011/154] (945s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-fullscreen)
[84.091222] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-fullscreen
[84.093032] Subtest fbchdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[84.347656] [012/154] (943s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier)
[85.855472] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier
[85.857517] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier: SKIP (0.000s)
[85.965672] [013/154] (941s left) kms_ccs (crc-primary-rotation-180-yf-tiled-ccs)
[87.465446] Starting subtest: crc-primary-rotation-180-yf-tiled-ccs
[87.466265] Starting dynamic subtest: pipe-A-HDMI-A-4
[87.474572] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.001s)
[87.484823] Starting dynamic subtest: pipe-B-HDMI-A-4
[87.493247] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.001s)
[87.503426] Starting dynamic subtest: pipe-C-HDMI-A-4
[87.511906] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.001s)
[87.522202] Starting dynamic subtest: pipe-D-HDMI-A-4
[87.530950] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.001s)
[87.532744] Subtest crc-primary-rotation-180-yf-tiled-ccs: SKIP (0.009s)
[87.754309] [014/154] (940s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs)
[89.243267] Starting subtest: ccs-on-another-bo-y-tiled-gen12-mc-ccs
[89.244251] Starting dynamic subtest: pipe-A-HDMI-A-4
[89.253999] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.000s)
[89.264526] Starting dynamic subtest: pipe-B-HDMI-A-4
[89.273907] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.000s)
[89.284092] Starting dynamic subtest: pipe-C-HDMI-A-4
[89.293608] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.000s)
[89.303866] Starting dynamic subtest: pipe-D-HDMI-A-4
[89.313586] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.000s)
[89.315463] Subtest ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[89.544944] [015/154] (938s left) kms_frontbuffer_tracking (hdr-rgb565-draw-blt)
[91.044921] Starting subtest: hdr-rgb565-draw-blt
[91.046568] Subtest hdr-rgb565-draw-blt: SKIP (0.000s)
[91.217669] [016/154] (936s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[91.272791] Starting subtest: x-tiled-to-vebox-y-tiled
[91.273618] Starting dynamic subtest: smem
[91.354325] Dynamic subtest smem: SUCCESS (0.082s)
[91.364428] Starting dynamic subtest: lmem0
[92.113537] Dynamic subtest lmem0: SUCCESS (0.756s)
[92.114516] Subtest x-tiled-to-vebox-y-tiled: SUCCESS (0.839s)
[92.371653] [017/154] (935s left) sysfs_heartbeat_interval (invalid)
[92.421426] Starting subtest: invalid
[92.422253] Starting dynamic subtest: vecs0
[92.423103] Dynamic subtest vecs0: SUCCESS (0.001s)
[92.434200] Starting dynamic subtest: vcs0
[92.435231] Dynamic subtest vcs0: SUCCESS (0.000s)
[92.445583] Starting dynamic subtest: bcs0
[92.446479] Dynamic subtest bcs0: SUCCESS (0.000s)
[92.456218] Starting dynamic subtest: rcs0
[92.457292] Dynamic subtest rcs0: SUCCESS (0.000s)
[92.467261] Starting dynamic subtest: vcs1
[92.468368] Dynamic subtest vcs1: SUCCESS (0.000s)
[92.469462] Subtest invalid: SUCCESS (0.004s)
[92.532476] [018/154] (935s left) kms_frontbuffer_tracking (fbc-stridechange)
[94.027235] Starting subtest: fbc-stridechange
[96.057083] Subtest fbc-stridechange: SUCCESS (2.030s)
[96.269812] [019/154] (932s left) gem_ctx_param (basic-default)
[96.315248] Starting subtest: basic-default
[96.316163] Subtest basic-default: SUCCESS (0.000s)
[96.437652] [020/154] (932s left) kms_psr (fbc-pr-primary-mmap-gtt)
[97.927035] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s)
[97.994545] [021/154] (930s left) i915_pm_freq_mult (media-freq)
[98.039805] Starting subtest: media-freq
[98.040692] Starting dynamic subtest: gt0
[98.042299] Dynamic subtest gt0: SKIP (0.000s)
[98.044112] Subtest media-freq: SKIP (0.001s)
[98.162045] [022/154] (930s left) prime_busy (before-wait)
[98.211624] Starting subtest: before-wait
[98.217519] Starting dynamic subtest: rcs0
[98.261398] Dynamic subtest rcs0: SUCCESS (0.044s)
[98.271954] Starting dynamic subtest: bcs0
[98.302191] Dynamic subtest bcs0: SUCCESS (0.040s)
[98.314475] Starting dynamic subtest: vcs0
[98.343384] Dynamic subtest vcs0: SUCCESS (0.041s)
[98.362103] Starting dynamic subtest: vcs1
[98.387318] Dynamic subtest vcs1: SUCCESS (0.043s)
[98.405608] Starting dynamic subtest: vecs0
[98.430681] Dynamic subtest vecs0: SUCCESS (0.043s)
[98.555491] Subtest before-wait: SUCCESS (0.340s)
[98.690309] [023/154] (929s left) gem_exec_gttfill (engines)
[98.748444] Starting subtest: engines
[98.749362] Starting dynamic subtest: rcs0
[126.314368] Dynamic subtest rcs0: SUCCESS (27.563s)
[126.326052] Starting dynamic subtest: bcs0
[154.089357] Dynamic subtest bcs0: SUCCESS (27.774s)
[154.101058] Starting dynamic subtest: vcs0
[181.857789] Dynamic subtest vcs0: SUCCESS (27.768s)
[181.868709] Starting dynamic subtest: vcs1
[209.597407] Dynamic subtest vcs1: SUCCESS (27.739s)
[209.608365] Starting dynamic subtest: vecs0
[237.394204] Dynamic subtest vecs0: SUCCESS (27.796s)
[237.395131] Subtest engines: SUCCESS (138.642s)
[237.755543] [024/154] (790s left) syncobj_timeline (etime-multi-wait-all-submitted)
[237.856914] Starting subtest: etime-multi-wait-all-submitted
[237.965430] Subtest etime-multi-wait-all-submitted: SUCCESS (0.105s)
[238.066421] [025/154] (790s left) gem_ctx_param (get-priority-new-ctx)
[238.111680] Starting subtest: get-priority-new-ctx
[238.112589] Subtest get-priority-new-ctx: SUCCESS (0.001s)
[238.216606] [026/154] (790s left) kms_big_fb (linear-32bpp-rotate-90)
[239.731450] Starting subtest: linear-32bpp-rotate-90
[239.848079] Subtest linear-32bpp-rotate-90: SKIP (0.115s)
[240.011412] [027/154] (788s left) gem_lmem_swapping (heavy-verify-multi-ccs)
[244.470559] Starting subtest: heavy-verify-multi-ccs
[244.471459] Starting dynamic subtest: lmem0
[244.473334] Dynamic subtest lmem0: SKIP (0.000s)
[244.476393] Subtest heavy-verify-multi-ccs: SKIP (0.000s)
[248.502802] [FACT gem_lmem_swapping (heavy-verify-multi-ccs)] deleted: kernel.kmod_is_loaded.i915: true
[248.504744] [028/154] (780s left) gem_ctx_shared (disjoint-timelines)
[251.863587] Starting subtest: disjoint-timelines
[251.949740] Subtest disjoint-timelines: SUCCESS (0.086s)
[252.278601] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.i915: true
[252.279682] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.vgem: true
[252.282075] [029/154] (776s left) kms_psr (fbc-psr2-cursor-plane-move)
[253.793421] Subtest fbc-psr2-cursor-plane-move: SKIP (0.000s)
[253.866745] [030/154] (775s left) gem_bad_reloc (negative-reloc-bltcopy)
[253.919534] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[254.027752] [031/154] (775s left) kms_chamelium_hpd (hdmi-hpd)
[255.561938] Subtest hdmi-hpd: SKIP (0.000s)
[255.691365] [032/154] (773s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-fully-sf)
[258.243111] Subtest pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[258.321766] [033/154] (771s left) gem_eio (wait-10ms)
[258.459844] Starting subtest: wait-10ms
[258.797194] Subtest wait-10ms: SUCCESS (0.337s)
[260.429875] [034/154] (768s left) kms_content_protection (legacy-hdcp14)
[261.989987] Starting subtest: legacy-hdcp14
[261.994111] Subtest legacy-hdcp14: SKIP (0.002s)
[262.193387] [035/154] (767s left) syncobj_wait (reset-signaled)
[262.241126] Starting subtest: reset-signaled
[262.242101] Subtest reset-signaled: SUCCESS (0.000s)
[262.353439] [036/154] (767s left) gem_ctx_isolation (dirty-switch)
[262.408456] Starting subtest: dirty-switch
[262.409308] Starting dynamic subtest: rcs0
[263.226638] Dynamic subtest rcs0: SUCCESS (0.818s)
[263.240943] Starting dynamic subtest: bcs0
[264.066041] Dynamic subtest bcs0: SUCCESS (0.839s)
[264.079535] Starting dynamic subtest: vcs0
[264.909270] Dynamic subtest vcs0: SUCCESS (0.843s)
[264.922112] Starting dynamic subtest: vcs1
[265.731571] Dynamic subtest vcs1: SUCCESS (0.822s)
[265.743795] Starting dynamic subtest: vecs0
[266.573482] Dynamic subtest vecs0: SUCCESS (0.841s)
[266.574332] Subtest dirty-switch: SUCCESS (4.165s)
[266.850854] [037/154] (762s left) gem_flink_basic (bad-open)
[266.896928] Starting subtest: bad-open
[266.897834] Subtest bad-open: SUCCESS (0.000s)
[267.020984] [038/154] (762s left) gem_caching (writes)
[267.070636] Starting subtest: writes
[272.379138] Subtest writes: SUCCESS (5.308s)
[272.536285] [039/154] (757s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[274.026230] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[274.027129] Starting dynamic subtest: pipe-A
[274.811407] Dynamic subtest pipe-A: SUCCESS (0.785s)
[274.823901] Starting dynamic subtest: pipe-B
[275.586119] Dynamic subtest pipe-B: SUCCESS (0.774s)
[275.607946] Starting dynamic subtest: pipe-C
[276.376550] Dynamic subtest pipe-C: SUCCESS (0.790s)
[276.389702] Starting dynamic subtest: pipe-D
[277.150012] Dynamic subtest pipe-D: SUCCESS (0.773s)
[277.151058] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (3.124s)
[277.380082] [040/154] (752s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[277.426891] Starting subtest: single-wait-all-for-submit-available-submitted
[277.427847] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[277.576085] [041/154] (752s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-render)
[279.072181] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-render
[279.074178] Subtest fbchdr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[279.251938] [042/154] (750s left) kms_pm_rpm (system-suspend-idle)
[283.004639] Starting subtest: system-suspend-idle
[302.447722] Subtest system-suspend-idle: SUCCESS (4.519s)
[307.512980] [043/154] (726s left) kms_atomic_transition (modeset-transition)
[309.931928] Starting subtest: modeset-transition
[309.932718] Starting dynamic subtest: 1x-outputs
[311.950080] Dynamic subtest 1x-outputs: SUCCESS (2.053s)
[311.951228] Subtest modeset-transition: SUCCESS (2.053s)
[312.161897] [044/154] (722s left) kms_frontbuffer_tracking (pipe-fbc-rte)
[313.638970] Starting subtest: pipe-fbc-rte
[313.644798] Subtest pipe-fbc-rte: SKIP (0.000s)
[313.833974] [045/154] (720s left) gem_spin_batch (resubmit-all)
[313.890943] Starting subtest: resubmit-all
[313.891869] Starting dynamic subtest: rcs0
[313.913775] Dynamic subtest rcs0: SUCCESS (0.023s)
[313.929428] Starting dynamic subtest: bcs0
[313.930679] Dynamic subtest bcs0: SUCCESS (0.003s)
[313.944360] Starting dynamic subtest: vcs0
[313.945548] Dynamic subtest vcs0: SUCCESS (0.002s)
[313.959015] Starting dynamic subtest: vcs1
[313.960053] Dynamic subtest vcs1: SUCCESS (0.002s)
[313.973460] Starting dynamic subtest: vecs0
[313.974483] Dynamic subtest vecs0: SUCCESS (0.002s)
[313.975627] Subtest resubmit-all: SUCCESS (0.037s)
[314.293691] [046/154] (720s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff)
[315.773498] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff
[315.775645] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[315.975772] [047/154] (718s left) syncobj_basic (illegal-fd-to-handle)
[316.020487] Starting subtest: illegal-fd-to-handle
[316.021361] Subtest illegal-fd-to-handle: SUCCESS (0.000s)
[316.148397] [048/154] (718s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite)
[317.630732] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite
[317.632679] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[317.812656] [049/154] (717s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[319.329919] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[320.600477] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.274s)
[320.807734] [050/154] (714s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[320.853083] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[320.963050] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.106s)
[321.133695] [051/154] (713s left) api_intel_bb (misplaced-blitter)
[321.179322] Starting subtest: misplaced-blitter
[321.201110] Subtest misplaced-blitter: SUCCESS (0.022s)
[321.421002] [052/154] (713s left) kms_atomic_interruptible (universal-setplane-cursor)
[322.904529] Starting subtest: universal-setplane-cursor
[322.906637] Starting dynamic subtest: pipe-A-HDMI-A-4
[329.152016] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (6.264s)
[329.152981] Subtest universal-setplane-cursor: SUCCESS (6.266s)
[329.342297] [053/154] (705s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[330.848389] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[330.850451] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[331.011162] [054/154] (704s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[332.505549] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[332.507276] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[332.693412] [055/154] (702s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[334.184440] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[334.185541] Starting dynamic subtest: pipe-A-valid-mode
[335.538967] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.353s)
[335.539855] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SUCCESS (1.355s)
[335.754390] [056/154] (699s left) gem_pxp (create-protected-buffer)
[338.335957] Starting subtest: create-protected-buffer
[338.338119] Subtest create-protected-buffer: SKIP (0.000s)
[338.442852] [057/154] (697s left) gen9_exec_parse (allowed-single)
[338.494855] Subtest allowed-single: SKIP (0.000s)
[338.572639] [058/154] (697s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[340.067497] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[340.069210] Subtest psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[340.262271] [059/154] (695s left) gem_exec_balancer (bonded-semaphore)
[340.320758] Starting subtest: bonded-semaphore
[340.322998] Subtest bonded-semaphore: SKIP (0.000s)
[340.831092] [060/154] (695s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping)
[342.320968] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping
[342.355937] Starting dynamic subtest: pipe-A-plane-0
[342.873303] Dynamic subtest pipe-A-plane-0: SUCCESS (0.516s)
[342.922776] Starting dynamic subtest: pipe-A-plane-3
[343.189549] Dynamic subtest pipe-A-plane-3: SUCCESS (0.312s)
[343.236228] Starting dynamic subtest: pipe-A-plane-5
[343.505417] Dynamic subtest pipe-A-plane-5: SUCCESS (0.312s)
[343.706063] Starting dynamic subtest: pipe-B-plane-0
[344.093317] Dynamic subtest pipe-B-plane-0: SUCCESS (0.400s)
[344.111602] Starting dynamic subtest: pipe-B-plane-3
[344.442668] Dynamic subtest pipe-B-plane-3: SUCCESS (0.346s)
[344.490527] Starting dynamic subtest: pipe-B-plane-5
[344.775260] Dynamic subtest pipe-B-plane-5: SUCCESS (0.330s)
[344.830735] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping: SUCCESS (2.512s)
[345.053702] [061/154] (690s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc)
[346.550035] Starting subtest: fbc-rgb565-draw-mmap-wc
[346.551663] Subtest fbc-rgb565-draw-mmap-wc: SKIP (0.000s)
[346.743651] [062/154] (689s left) kms_pwrite_crc (basic)
[348.233866] Starting subtest: basic
[348.892006] Subtest basic: SUCCESS (0.658s)
[349.112085] [063/154] (687s left) gem_flink_basic (bad-flink)
[349.159433] Starting subtest: bad-flink
[349.160611] Subtest bad-flink: SUCCESS (0.000s)
[349.272102] [064/154] (686s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-move)
[350.766416] Starting subtest: hdr-2p-primscrn-spr-indfb-move
[350.768201] Subtest hdr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[350.952109] [065/154] (685s left) gem_ctx_persistence (legacy-engines-persistence)
[351.004309] Starting subtest: legacy-engines-persistence
[351.005236] Starting dynamic subtest: render
[351.803884] Dynamic subtest render: SUCCESS (0.799s)
[351.821510] Starting dynamic subtest: bsd1
[352.565774] Dynamic subtest bsd1: SUCCESS (0.760s)
[352.583946] Starting dynamic subtest: bsd2
[353.336770] Dynamic subtest bsd2: SUCCESS (0.769s)
[353.354013] Starting dynamic subtest: blt
[354.100782] Dynamic subtest blt: SUCCESS (0.762s)
[354.118008] Starting dynamic subtest: vebox
[354.873737] Dynamic subtest vebox: SUCCESS (0.771s)
[354.874820] Subtest legacy-engines-persistence: SUCCESS (3.872s)
[355.026829] [066/154] (681s left) gen7_exec_parse (basic-allowed)
[355.078043] Subtest basic-allowed: SKIP (0.000s)
[355.163922] [067/154] (681s left) gem_exec_schedule (reorder-wide)
[355.382713] Subtest reorder-wide: SKIP (0.000s)
[355.640323] [068/154] (680s left) kms_frontbuffer_tracking (psrhdr-slowdraw)
[357.134332] Starting subtest: psrhdr-slowdraw
[357.135994] Subtest psrhdr-slowdraw: SKIP (0.000s)
[357.317186] [069/154] (679s left) perf (non-zero-reason)
[357.468363] Starting subtest: non-zero-reason
[357.469284] Starting dynamic subtest: 0-rcs0
[368.506099] Dynamic subtest 0-rcs0: SUCCESS (11.038s)
[368.507217] Subtest non-zero-reason: SUCCESS (11.039s)
[368.580215] [070/154] (668s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-pwrite)
[370.073567] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-pwrite
[370.075551] Subtest fbchdr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[370.296997] [071/154] (666s left) i915_drm_fdinfo (all-busy-check-all)
[370.366410] Starting subtest: all-busy-check-all
[370.385456] Subtest all-busy-check-all: SKIP (0.018s)
[370.502309] [072/154] (666s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[372.035262] Starting subtest: psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[372.036941] Subtest psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[372.198936] [073/154] (664s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[373.689988] Starting subtest: compare-crc-sanitycheck-nv12
[373.690922] Starting dynamic subtest: pipe-A-HDMI-A-4
[374.347482] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.657s)
[374.363625] Starting dynamic subtest: pipe-B-HDMI-A-4
[374.900763] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (0.550s)
[374.919344] Starting dynamic subtest: pipe-C-HDMI-A-4
[375.435884] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (0.532s)
[375.454477] Starting dynamic subtest: pipe-D-HDMI-A-4
[375.955531] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (0.516s)
[375.956691] Subtest compare-crc-sanitycheck-nv12: SUCCESS (2.267s)
[376.170077] [074/154] (660s left) gem_exec_reloc (basic-write-gtt)
[376.223398] Subtest basic-write-gtt: SKIP (0.000s)
[376.339875] [075/154] (660s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[378.145036] Starting subtest: cursor-rapid-movement-max-size
[378.147715] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[378.383564] [076/154] (658s left) kms_color_pipeline (plane-lut1d)
[379.882739] Starting subtest: plane-lut1d
[380.090193] Starting dynamic subtest: pipe-A-plane-0
[380.337687] Dynamic subtest pipe-A-plane-0: SUCCESS (0.247s)
[380.386716] Starting dynamic subtest: pipe-A-plane-1
[380.537697] Dynamic subtest pipe-A-plane-1: SUCCESS (0.194s)
[380.583867] Starting dynamic subtest: pipe-A-plane-2
[380.754297] Dynamic subtest pipe-A-plane-2: SUCCESS (0.211s)
[381.054554] Starting dynamic subtest: pipe-B-plane-0
[381.202385] Dynamic subtest pipe-B-plane-0: SUCCESS (0.164s)
[381.244065] Starting dynamic subtest: pipe-B-plane-1
[381.402697] Dynamic subtest pipe-B-plane-1: SUCCESS (0.194s)
[381.444046] Starting dynamic subtest: pipe-B-plane-2
[381.618296] Dynamic subtest pipe-B-plane-2: SUCCESS (0.211s)
[381.980351] Starting dynamic subtest: pipe-C-plane-0
[382.151784] Dynamic subtest pipe-C-plane-0: SUCCESS (0.214s)
[382.197118] Starting dynamic subtest: pipe-C-plane-1
[382.368391] Dynamic subtest pipe-C-plane-1: SUCCESS (0.210s)
[382.412520] Starting dynamic subtest: pipe-C-plane-2
[382.584390] Dynamic subtest pipe-C-plane-2: SUCCESS (0.210s)
[382.898546] Starting dynamic subtest: pipe-D-plane-0
[383.079165] Dynamic subtest pipe-D-plane-0: SUCCESS (0.214s)
[383.115060] Starting dynamic subtest: pipe-D-plane-1
[383.279202] Dynamic subtest pipe-D-plane-1: SUCCESS (0.194s)
[383.297635] Starting dynamic subtest: pipe-D-plane-2
[383.462479] Dynamic subtest pipe-D-plane-2: SUCCESS (0.178s)
[383.517417] Subtest plane-lut1d: SUCCESS (3.637s)
[383.730302] [077/154] (653s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[385.240493] Starting subtest: yf-tiled-16bpp-rotate-180
[385.242239] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[385.429861] [078/154] (652s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[386.939372] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[386.941298] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[387.121762] [079/154] (650s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[388.616834] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[388.618540] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[388.837745] [080/154] (648s left) gem_exec_reloc (basic-wc-read)
[388.890850] Subtest basic-wc-read: SKIP (0.000s)
[388.989241] [081/154] (648s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[390.479829] Starting subtest: short-busy-flip-before-cursor-toggle
[390.481650] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[390.601002] [082/154] (647s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[392.100820] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[392.119099] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.017s)
[392.276966] [083/154] (645s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[393.773316] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[393.775106] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[393.952901] [084/154] (644s left) kms_draw_crc (draw-method-render)
[395.443133] Starting subtest: draw-method-render
[395.444003] Starting dynamic subtest: xrgb8888-untiled
[395.856340] Dynamic subtest xrgb8888-untiled: SUCCESS (0.412s)
[395.905663] Starting dynamic subtest: rgb565-untiled
[396.222537] Dynamic subtest rgb565-untiled: SUCCESS (0.360s)
[396.240775] Starting dynamic subtest: xrgb2101010-untiled
[396.539063] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.312s)
[396.590463] Starting dynamic subtest: xbgr16161616f-untiled
[396.922025] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.377s)
[396.974098] Starting dynamic subtest: xrgb8888-xtiled
[397.122070] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.194s)
[397.174097] Starting dynamic subtest: rgb565-xtiled
[397.322222] Dynamic subtest rgb565-xtiled: SUCCESS (0.194s)
[397.371447] Starting dynamic subtest: xrgb2101010-xtiled
[397.505293] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.176s)
[397.554846] Starting dynamic subtest: xbgr16161616f-xtiled
[397.705388] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.193s)
[397.755028] Starting dynamic subtest: xrgb8888-ytiled
[397.905172] Dynamic subtest xrgb8888-ytiled: SUCCESS (0.193s)
[397.955208] Starting dynamic subtest: rgb565-ytiled
[398.105241] Dynamic subtest rgb565-ytiled: SUCCESS (0.193s)
[398.155133] Starting dynamic subtest: xrgb2101010-ytiled
[398.288608] Dynamic subtest xrgb2101010-ytiled: SUCCESS (0.177s)
[398.339549] Starting dynamic subtest: xbgr16161616f-ytiled
[398.488744] Dynamic subtest xbgr16161616f-ytiled: SUCCESS (0.192s)
[398.496587] Subtest draw-method-render: SUCCESS (3.049s)
[398.773882] [085/154] (639s left) kms_cursor_crc (cursor-sliding-256x85)
[400.509105] Starting subtest: cursor-sliding-256x85
[400.513743] Starting dynamic subtest: pipe-A-HDMI-A-4
[404.630587] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (4.114s)
[404.670903] Starting dynamic subtest: pipe-D-HDMI-A-4
[409.010974] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (4.353s)
[409.011846] Subtest cursor-sliding-256x85: SUCCESS (8.500s)
[409.356131] [086/154] (629s left) kms_cursor_crc (cursor-onscreen-256x256)
[411.066917] Starting subtest: cursor-onscreen-256x256
[411.073547] Starting dynamic subtest: pipe-A-HDMI-A-4
[413.729413] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (2.655s)
[413.768309] Starting dynamic subtest: pipe-D-HDMI-A-4
[416.429829] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (2.673s)
[416.430902] Subtest cursor-onscreen-256x256: SUCCESS (5.361s)
[416.805067] [087/154] (621s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render)
[418.304764] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render
[418.307037] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[418.504940] [088/154] (620s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[420.008873] Starting subtest: fbcpsr-slowdraw
[420.010585] Subtest fbcpsr-slowdraw: SKIP (0.000s)
[420.180692] [089/154] (618s left) kms_lease (cursor-implicit-plane)
[421.674244] Starting subtest: cursor-implicit-plane
[421.675178] Starting dynamic subtest: pipe-A-HDMI-A-4
[422.068460] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.393s)
[422.086257] Starting dynamic subtest: pipe-B-HDMI-A-4
[422.299198] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (0.228s)
[422.317803] Starting dynamic subtest: pipe-C-HDMI-A-4
[422.530835] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (0.229s)
[422.551207] Starting dynamic subtest: pipe-D-HDMI-A-4
[422.771821] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (0.236s)
[422.772707] Subtest cursor-implicit-plane: SUCCESS (1.099s)
[422.977913] [090/154] (615s left) syncobj_wait (invalid-reset-zero-handles)
[423.027028] Starting subtest: invalid-reset-zero-handles
[423.028027] Subtest invalid-reset-zero-handles: SUCCESS (0.000s)
[423.161316] [091/154] (615s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render)
[424.660239] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render
[424.662097] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[424.864059] [092/154] (614s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[426.373943] Starting subtest: yf-tiled-64bpp-rotate-90
[426.376068] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[426.531310] [093/154] (612s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc)
[428.028529] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc
[428.030536] Subtest fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[428.226242] [094/154] (610s left) gem_exec_fence (long-history)
[428.675422] Starting subtest: long-history
[434.741970] Subtest long-history: SUCCESS (6.065s)
[435.133048] [095/154] (604s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt)
[436.627331] Starting subtest: psr-1p-primscrn-indfb-msflip-blt
[436.629077] Subtest psr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[436.847456] [096/154] (602s left) kms_ccs (bad-rotation-90-yf-tiled-ccs)
[438.340964] Starting subtest: bad-rotation-90-yf-tiled-ccs
[438.341995] Starting dynamic subtest: pipe-A-HDMI-A-4
[438.351418] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.000s)
[438.366598] Starting dynamic subtest: pipe-B-HDMI-A-4
[438.376376] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.000s)
[438.391249] Starting dynamic subtest: pipe-C-HDMI-A-4
[438.400599] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.000s)
[438.414918] Starting dynamic subtest: pipe-D-HDMI-A-4
[438.425381] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.000s)
[438.427711] Subtest bad-rotation-90-yf-tiled-ccs: SKIP (0.010s)
[438.655790] [097/154] (600s left) kms_cursor_legacy (long-nonblocking-modeset-vs-cursor-atomic)
[440.147180] Starting subtest: long-nonblocking-modeset-vs-cursor-atomic
[442.186817] Subtest long-nonblocking-modeset-vs-cursor-atomic: SUCCESS (2.041s)
[442.380234] [098/154] (597s left) kms_chamelium_audio (dp-audio-edid)
[443.916705] Subtest dp-audio-edid: SKIP (0.000s)
[444.053082] [099/154] (595s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[445.545242] Starting subtest: addfb25-yf-tiled-legacy
[445.546212] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[445.698343] [100/154] (594s left) kms_frontbuffer_tracking (hdr-1p-primscrn-indfb-pgflip-blt)
[447.195939] Starting subtest: hdr-1p-primscrn-indfb-pgflip-blt
[447.197875] Subtest hdr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[447.389744] [101/154] (592s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff)
[448.885601] Starting subtest: psr-2p-primscrn-cur-indfb-onoff
[448.887367] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[449.104309] [102/154] (590s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw)
[450.598941] Starting subtest: fbc-2p-pri-indfb-multidraw
[450.600575] Subtest fbc-2p-pri-indfb-multidraw: SKIP (0.000s)
[450.789574] [103/154] (589s left) kms_sequence (queue-busy)
[452.281606] Starting subtest: queue-busy
[452.282674] Starting dynamic subtest: pipe-A-HDMI-A-4
[454.872607] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (2.591s)
[454.889429] Starting dynamic subtest: pipe-B-HDMI-A-4
[457.299374] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (2.425s)
[457.317046] Starting dynamic subtest: pipe-C-HDMI-A-4
[459.729347] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (2.428s)
[459.746023] Starting dynamic subtest: pipe-D-HDMI-A-4
[462.165577] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (2.434s)
[462.166425] Subtest queue-busy: SUCCESS (9.888s)
[462.378734] [104/154] (577s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[463.869972] Starting subtest: plane-upscale-20x20-with-rotation
[463.870867] Starting dynamic subtest: pipe-A
[464.301086] Dynamic subtest pipe-A: SKIP (0.431s)
[464.315780] Starting dynamic subtest: pipe-B
[464.770187] Dynamic subtest pipe-B: SKIP (0.467s)
[464.784946] Starting dynamic subtest: pipe-C
[465.227885] Dynamic subtest pipe-C: SKIP (0.456s)
[465.243164] Starting dynamic subtest: pipe-D
[465.700198] Dynamic subtest pipe-D: SKIP (0.471s)
[465.702137] Subtest plane-upscale-20x20-with-rotation: SKIP (1.831s)
[465.914252] [105/154] (574s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-pwrite)
[467.410883] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-pwrite
[467.412697] Subtest psrhdr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[467.546692] [106/154] (572s left) gem_exec_reloc (basic-write-wc)
[467.599392] Subtest basic-write-wc: SKIP (0.000s)
[467.690615] [107/154] (572s left) syncobj_timeline (reset-signaled)
[467.737441] Starting subtest: reset-signaled
[467.738450] Subtest reset-signaled: SUCCESS (0.001s)
[467.890574] [108/154] (572s left) kms_psr (pr-cursor-plane-move)
[469.379825] Subtest pr-cursor-plane-move: SKIP (0.000s)
[469.457645] [109/154] (571s left) syncobj_wait (invalid-wait-illegal-handle)
[469.504382] Starting subtest: invalid-wait-illegal-handle
[469.505257] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[469.648782] [110/154] (571s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[471.145144] Starting subtest: extended-pageflip-modeset-hang-oldfb
[471.147674] Starting dynamic subtest: pipe-A
[478.877994] Dynamic subtest pipe-A: SUCCESS (7.733s)
[478.896468] Starting dynamic subtest: pipe-D
[486.610999] Dynamic subtest pipe-D: SUCCESS (7.731s)
[486.611922] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (15.472s)
[486.856954] [111/154] (553s left) kms_psr (psr2-primary-blt)
[488.345007] Subtest psr2-primary-blt: SKIP (0.000s)
[488.422765] [112/154] (552s left) gem_softpin (evict-snoop-interruptible)
[488.479636] Starting subtest: evict-snoop-interruptible
[488.481957] Subtest evict-snoop-interruptible: SKIP (0.000s)
[488.615852] [113/154] (552s left) kms_force_connector_basic (force-load-detect)
[488.748586] Starting subtest: force-load-detect
[488.750716] Subtest force-load-detect: SKIP (0.000s)
[489.695408] [114/154] (551s left) kms_chamelium_edid (hdmi-mode-timings)
[491.229784] Subtest hdmi-mode-timings: SKIP (0.000s)
[491.365205] [115/154] (549s left) syncobj_timeline (invalid-signal-zero-handles)
[491.411980] Starting subtest: invalid-signal-zero-handles
[491.412853] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[491.538453] [116/154] (549s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt)
[493.033797] Starting subtest: fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt
[493.035606] Subtest fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[493.205413] [117/154] (548s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[494.701039] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[494.703001] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[494.922321] [118/154] (546s left) gem_mmap_gtt (fault-concurrent-x)
[494.969706] Subtest fault-concurrent-X: SKIP (0.000s)
[495.089972] [119/154] (546s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[495.145194] Starting subtest: y-tiled-to-vebox-y-tiled
[495.146095] Starting dynamic subtest: smem
[495.227239] Dynamic subtest smem: SUCCESS (0.081s)
[495.244225] Starting dynamic subtest: lmem0
[495.975557] Dynamic subtest lmem0: SUCCESS (0.744s)
[495.976710] Subtest y-tiled-to-vebox-y-tiled: SUCCESS (0.828s)
[496.236324] [120/154] (545s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-shrfb-plflip-blt)
[497.732846] Starting subtest: psrhdr-2p-scndscrn-shrfb-plflip-blt
[497.734801] Subtest psrhdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[497.897528] [121/154] (543s left) vgem_basic (debugfs)
[497.962476] Starting subtest: debugfs
[497.963549] Subtest debugfs: SUCCESS (0.000s)
[498.066486] [122/154] (543s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[499.556307] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[499.557292] Starting dynamic subtest: pipe-A-valid-mode
[500.878304] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.322s)
[500.879123] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SUCCESS (1.324s)
[501.083383] [123/154] (540s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite)
[502.578648] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite
[502.580614] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[502.781324] [124/154] (539s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[504.276979] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[504.278878] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[504.440229] [125/154] (537s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt)
[505.937640] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt
[505.939532] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[506.118934] [126/154] (536s left) kms_vblank (wait-forked-hang)
[507.609409] Starting subtest: wait-forked-hang
[507.610375] Starting dynamic subtest: pipe-A-HDMI-A-4
[534.388840] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (26.780s)
[534.407328] Starting dynamic subtest: pipe-D-HDMI-A-4
[559.387454] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (24.987s)
[559.388398] Subtest wait-forked-hang: SUCCESS (51.776s)
[559.600012] [127/154] (482s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[561.090389] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[561.091398] Starting dynamic subtest: pipe-A-valid-mode
[563.659782] Dynamic subtest pipe-A-valid-mode: SUCCESS (2.568s)
[563.660687] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SUCCESS (2.570s)
[563.862511] [128/154] (478s left) gem_ctx_exec (basic-invalid-context)
[563.911752] Starting subtest: basic-invalid-context
[563.915467] Subtest basic-invalid-context: SUCCESS (0.005s)
[564.060281] [129/154] (478s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-indfb-msflip-blt)
[565.556330] Starting subtest: fbcpsrhdr-1p-primscrn-indfb-msflip-blt
[565.558040] Subtest fbcpsrhdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[565.722446] [130/154] (476s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[567.218863] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (0.000s)
[567.301104] [131/154] (475s left) gem_ppgtt (blt-vs-render-ctxn)
[567.353855] Starting subtest: blt-vs-render-ctxN
[600.107622] Subtest blt-vs-render-ctxN: SUCCESS (32.749s)
[600.344749] [132/154] (442s left) gem_exec_reloc (basic-gtt-cpu-noreloc)
[600.454687] Subtest basic-gtt-cpu-noreloc: SKIP (0.000s)
[600.551733] [133/154] (442s left) gem_softpin (invalid)
[600.603346] Starting subtest: invalid
[600.615932] Subtest invalid: SUCCESS (0.013s)
[600.780121] [134/154] (442s left) gem_exec_capture (capture)
[600.833351] Starting subtest: capture
[600.834185] Starting dynamic subtest: rcs0-smem
[601.742223] Dynamic subtest rcs0-smem: SUCCESS (0.907s)
[601.759999] Starting dynamic subtest: rcs0-lmem0
[602.622990] Dynamic subtest rcs0-lmem0: SUCCESS (0.880s)
[602.638736] Starting dynamic subtest: bcs0-smem
[604.001111] Dynamic subtest bcs0-smem: SUCCESS (1.376s)
[604.016638] Starting dynamic subtest: bcs0-lmem0
[605.106993] Dynamic subtest bcs0-lmem0: SUCCESS (1.104s)
[605.122243] Starting dynamic subtest: vcs0-smem
[605.966136] Dynamic subtest vcs0-smem: SUCCESS (0.858s)
[605.981462] Starting dynamic subtest: vcs0-lmem0
[606.824030] Dynamic subtest vcs0-lmem0: SUCCESS (0.856s)
[606.839261] Starting dynamic subtest: vcs1-smem
[607.688492] Dynamic subtest vcs1-smem: SUCCESS (0.862s)
[607.703887] Starting dynamic subtest: vcs1-lmem0
[608.552906] Dynamic subtest vcs1-lmem0: SUCCESS (0.864s)
[608.568075] Starting dynamic subtest: vecs0-smem
[609.961622] Dynamic subtest vecs0-smem: FAIL (1.387s)
[609.976629] Starting dynamic subtest: vecs0-lmem0
[610.961283] Dynamic subtest vecs0-lmem0: FAIL (0.992s)
[610.962421] Subtest capture: FAIL (10.102s)
[611.095061] [135/154] (431s left) gem_tiled_blits (basic)
[611.146492] Subtest basic: SKIP (0.000s)
[611.234407] [136/154] (431s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt)
[612.734379] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt
[612.736153] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[612.924482] [137/154] (430s left) gem_mmap_offset (partial-remap)
[612.987945] Starting subtest: partial-remap
[612.988779] Starting dynamic subtest: lmem0
[612.994603] Dynamic subtest lmem0: SUCCESS (0.005s)
[613.009994] Starting dynamic subtest: smem0
[613.012034] Dynamic subtest smem0: SUCCESS (0.005s)
[613.013036] Subtest partial-remap: SUCCESS (0.014s)
[613.220135] [138/154] (430s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-blt)
[614.720620] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-blt
[614.722526] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s)
[614.905509] [139/154] (428s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-blt)
[616.401216] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-blt
[616.403192] Subtest hdr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[616.593887] [140/154] (427s left) i915_power (sanity)
[616.639873] Starting subtest: sanity
[620.679037] Subtest sanity: SUCCESS (4.035s)
[620.825356] [141/154] (422s left) gem_exec_schedule (preempt-queue)
[620.895985] Subtest preempt-queue: SKIP (0.000s)
[621.268203] [142/154] (422s left) kms_cursor_legacy (cursorb-vs-flipa-legacy)
[622.757769] Starting subtest: cursorB-vs-flipA-legacy
[622.760820] Subtest cursorB-vs-flipA-legacy: SKIP (0.002s)
[622.876725] [143/154] (420s left) i915_pm_rps (reset)
[622.936217] Starting subtest: reset
[627.657628] Subtest reset: SUCCESS (4.722s)
[627.845676] [144/154] (416s left) kms_psr (fbc-psr-cursor-plane-onoff)
[629.337133] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.000s)
[629.416663] [145/154] (414s left) kms_setmode (clone-exclusive-crtc)
[629.463703] Starting subtest: clone-exclusive-crtc
[629.466375] Subtest clone-exclusive-crtc: SKIP (0.000s)
[629.623458] [146/154] (414s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[631.118621] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[631.120384] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[631.298746] [147/154] (412s left) kms_flip (flip-vs-rmfb)
[632.791051] Starting subtest: flip-vs-rmfb
[632.798399] Starting dynamic subtest: A-HDMI-A4
[640.602622] Dynamic subtest A-HDMI-A4: SUCCESS (7.805s)
[640.632219] Starting dynamic subtest: B-HDMI-A4
[648.349430] Dynamic subtest B-HDMI-A4: SUCCESS (7.744s)
[648.378752] Starting dynamic subtest: C-HDMI-A4
[656.083252] Dynamic subtest C-HDMI-A4: SUCCESS (7.731s)
[656.126550] Starting dynamic subtest: D-HDMI-A4
[663.834582] Dynamic subtest D-HDMI-A4: SUCCESS (7.747s)
[663.839644] Subtest flip-vs-rmfb: SUCCESS (31.051s)
[664.139325] [148/154] (380s left) gem_exec_whisper (basic-contexts-all)
[664.200599] Starting subtest: basic-contexts-all
[671.304350] Subtest basic-contexts-all: SUCCESS (7.100s)
[671.571045] [149/154] (372s left) dmabuf_sync_file (export-basic)
[671.624981] Starting subtest: export-basic
[671.625856] Subtest export-basic: SUCCESS (0.001s)
[671.744370] [150/154] (372s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[673.240312] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[673.241965] Subtest hdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[673.441977] [151/154] (371s left) gem_sync (basic-each)
[673.504402] Starting subtest: basic-each
[675.910545] Subtest basic-each: SUCCESS (2.407s)
[676.251994] [152/154] (368s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc)
[677.743421] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc
[677.744394] Starting dynamic subtest: pipe-A-HDMI-A-4
[677.755097] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.000s)
[677.771207] Starting dynamic subtest: pipe-B-HDMI-A-4
[677.781390] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.000s)
[677.796844] Starting dynamic subtest: pipe-C-HDMI-A-4
[677.812231] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.000s)
[677.827406] Starting dynamic subtest: pipe-D-HDMI-A-4
[677.838082] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.000s)
[677.839819] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.010s)
[678.047264] [153/154] (366s left) syncobj_wait (multi-wait-for-submit-submitted)
[678.093800] Starting subtest: multi-wait-for-submit-submitted
[678.203076] Subtest multi-wait-for-submit-submitted: SUCCESS (0.105s)
[678.391111] [154/154] (366s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[679.885458] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt
[679.887386] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[680.104599] 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: gem_render_copy
results: parsing output: 16/ for test: sysfs_heartbeat_interval
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: gem_ctx_param
results: parsing output: 19/ for test: kms_psr
results: parsing output: 20/ for test: i915_pm_freq_mult
results: parsing output: 21/ for test: prime_busy
results: parsing output: 22/ for test: gem_exec_gttfill
results: parsing output: 23/ for test: syncobj_timeline
results: parsing output: 24/ for test: gem_ctx_param
results: parsing output: 25/ for test: kms_big_fb
results: parsing output: 26/ for test: gem_lmem_swapping
results: parsing output: 27/ for test: gem_ctx_shared
results: parsing output: 28/ for test: kms_psr
results: parsing output: 29/ for test: gem_bad_reloc
results: parsing output: 30/ for test: kms_chamelium_hpd
results: parsing output: 31/ for test: kms_psr2_sf
results: parsing output: 32/ for test: gem_eio
results: parsing output: 33/ for test: kms_content_protection
results: parsing output: 34/ for test: syncobj_wait
results: parsing output: 35/ for test: gem_ctx_isolation
results: parsing output: 36/ for test: gem_flink_basic
results: parsing output: 37/ for test: gem_caching
results: parsing output: 38/ for test: kms_plane_scaling
results: parsing output: 39/ for test: syncobj_timeline
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: kms_pm_rpm
results: parsing output: 42/ for test: kms_atomic_transition
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: gem_spin_batch
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: syncobj_basic
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: kms_big_fb
results: parsing output: 49/ for test: syncobj_timeline
results: parsing output: 50/ for test: api_intel_bb
results: parsing output: 51/ for test: kms_atomic_interruptible
results: parsing output: 52/ for test: kms_big_fb
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: kms_flip_scaled_crc
results: parsing output: 55/ for test: gem_pxp
results: parsing output: 56/ for test: gen9_exec_parse
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: gem_exec_balancer
results: parsing output: 59/ for test: kms_plane
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: kms_pwrite_crc
results: parsing output: 62/ for test: gem_flink_basic
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: gem_ctx_persistence
results: parsing output: 65/ for test: gen7_exec_parse
results: parsing output: 66/ for test: gem_exec_schedule
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: perf
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: i915_drm_fdinfo
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: kms_pipe_crc_basic
results: parsing output: 73/ for test: gem_exec_reloc
results: parsing output: 74/ for test: kms_cursor_crc
results: parsing output: 75/ for test: kms_color_pipeline
results: parsing output: 76/ for test: kms_big_fb
results: parsing output: 77/ for test: kms_big_fb
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: gem_exec_reloc
results: parsing output: 80/ for test: kms_cursor_legacy
results: parsing output: 81/ for test: kms_flip
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: kms_draw_crc
results: parsing output: 84/ for test: kms_cursor_crc
results: parsing output: 85/ for test: kms_cursor_crc
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: kms_lease
results: parsing output: 89/ for test: syncobj_wait
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: kms_big_fb
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: gem_exec_fence
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: kms_ccs
results: parsing output: 96/ for test: kms_cursor_legacy
results: parsing output: 97/ for test: kms_chamelium_audio
results: parsing output: 98/ for test: kms_addfb_basic
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: kms_sequence
results: parsing output: 103/ for test: kms_plane_scaling
results: parsing output: 104/ for test: kms_frontbuffer_tracking
results: parsing output: 105/ for test: gem_exec_reloc
results: parsing output: 106/ for test: syncobj_timeline
results: parsing output: 107/ for test: kms_psr
results: parsing output: 108/ for test: syncobj_wait
results: parsing output: 109/ for test: kms_busy
results: parsing output: 110/ for test: kms_psr
results: parsing output: 111/ for test: gem_softpin
results: parsing output: 112/ for test: kms_force_connector_basic
results: parsing output: 113/ for test: kms_chamelium_edid
results: parsing output: 114/ for test: syncobj_timeline
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: gem_mmap_gtt
results: parsing output: 118/ for test: gem_render_copy
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: vgem_basic
results: parsing output: 121/ for test: kms_flip_scaled_crc
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: kms_vblank
results: parsing output: 126/ for test: kms_flip_scaled_crc
results: parsing output: 127/ for test: gem_ctx_exec
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: kms_psr2_sf
results: parsing output: 130/ for test: gem_ppgtt
results: parsing output: 131/ for test: gem_exec_reloc
results: parsing output: 132/ for test: gem_softpin
results: parsing output: 133/ for test: gem_exec_capture
results: parsing output: 134/ for test: gem_tiled_blits
results: parsing output: 135/ for test: kms_frontbuffer_tracking
results: parsing output: 136/ for test: gem_mmap_offset
results: parsing output: 137/ for test: kms_frontbuffer_tracking
results: parsing output: 138/ for test: kms_frontbuffer_tracking
results: parsing output: 139/ for test: i915_power
results: parsing output: 140/ for test: gem_exec_schedule
results: parsing output: 141/ for test: kms_cursor_legacy
results: parsing output: 142/ for test: i915_pm_rps
results: parsing output: 143/ for test: kms_psr
results: parsing output: 144/ for test: kms_setmode
results: parsing output: 145/ for test: kms_frontbuffer_tracking
results: parsing output: 146/ for test: kms_flip
results: parsing output: 147/ for test: gem_exec_whisper
results: parsing output: 148/ for test: dmabuf_sync_file
results: parsing output: 149/ for test: kms_frontbuffer_tracking
results: parsing output: 150/ for test: gem_sync
results: parsing output: 151/ for test: kms_ccs
results: parsing output: 152/ for test: syncobj_wait
results: parsing output: 153/ for test: kms_frontbuffer_tracking
Done.