[69.513575] Initializing watchdogs
[69.513657]   /dev/watchdog0
[69.545064] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:03:00.0: 8086:4905 Intel Dg1 (Gen12) DG1 [Iris Xe MAX Graphics]
[69.560031] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[69.564945] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[69.568409] [001/156] (960s left) kms_frontbuffer_tracking (fbc-tiling-linear)
[71.058141] Starting subtest: fbc-tiling-linear
[73.525745] Subtest fbc-tiling-linear: SUCCESS (2.506s)
[73.783349] [002/156] (956s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes)
[73.834290] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s)
[73.910996] [003/156] (956s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[75.396248] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[75.399364] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[75.551605] [004/156] (954s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[77.048738] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[77.051830] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[77.254652] [005/156] (953s left) kms_force_connector_basic (prune-stale-modes)
[77.386587] Starting subtest: prune-stale-modes
[77.408453] Subtest prune-stale-modes: SUCCESS (0.022s)
[79.047509] [006/156] (951s left) perf_pmu (most-busy-check-all)
[79.113661] Starting subtest: most-busy-check-all
[79.115322] Starting dynamic subtest: rcs0
[79.684145] Dynamic subtest rcs0: SUCCESS (0.573s)
[79.698851] Starting dynamic subtest: bcs0
[80.236892] Dynamic subtest bcs0: SUCCESS (0.555s)
[80.248805] Starting dynamic subtest: vcs0
[80.783637] Dynamic subtest vcs0: SUCCESS (0.549s)
[80.795742] Starting dynamic subtest: vcs1
[81.331626] Dynamic subtest vcs1: SUCCESS (0.550s)
[81.343429] Starting dynamic subtest: vecs0
[81.879654] Dynamic subtest vecs0: SUCCESS (0.550s)
[81.881267] Subtest most-busy-check-all: SUCCESS (2.779s)
[82.010084] [007/156] (948s left) gem_ccs (large-ctrl-surf-copy)
[82.063108] Starting subtest: large-ctrl-surf-copy
[82.066117] Subtest large-ctrl-surf-copy: SKIP (0.000s)
[82.163780] [008/156] (948s left) kms_plane_alpha_blend (coverage-7efc)
[83.648565] Starting subtest: coverage-7efc
[83.924129] Starting dynamic subtest: pipe-A-HDMI-A-4
[84.443719] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.510s)
[84.462680] Starting dynamic subtest: pipe-D-HDMI-A-4
[85.025556] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (0.573s)
[85.026965] Subtest coverage-7efc: SUCCESS (1.380s)
[85.214416] [009/156] (945s left) gem_mmap_gtt (fault-concurrent)
[85.267886] Subtest fault-concurrent: SKIP (0.000s)
[85.343848] [010/156] (945s left) api_intel_allocator (gem-pool)
[85.390892] Starting subtest: gem-pool
[85.416141] Subtest gem-pool: SUCCESS (0.025s)
[85.592881] [011/156] (945s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-fullscreen)
[87.086877] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-fullscreen
[87.090195] Subtest fbchdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[87.212460] [012/156] (943s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier)
[88.712452] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier
[88.715605] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier: SKIP (0.000s)
[88.839260] [013/156] (942s left) kms_ccs (crc-primary-rotation-180-yf-tiled-ccs)
[90.339213] Starting subtest: crc-primary-rotation-180-yf-tiled-ccs
[90.340753] Starting dynamic subtest: pipe-A-HDMI-A-4
[90.355783] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.001s)
[90.366703] Starting dynamic subtest: pipe-B-HDMI-A-4
[90.382397] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.001s)
[90.392853] Starting dynamic subtest: pipe-C-HDMI-A-4
[90.409038] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.001s)
[90.419763] Starting dynamic subtest: pipe-D-HDMI-A-4
[90.440430] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.001s)
[90.444087] Subtest crc-primary-rotation-180-yf-tiled-ccs: SKIP (0.008s)
[90.621333] [014/156] (940s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs)
[92.108565] Starting subtest: ccs-on-another-bo-y-tiled-gen12-mc-ccs
[92.110215] Starting dynamic subtest: pipe-A-HDMI-A-4
[92.126896] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.000s)
[92.137993] Starting dynamic subtest: pipe-B-HDMI-A-4
[92.162124] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.000s)
[92.172559] Starting dynamic subtest: pipe-C-HDMI-A-4
[92.186899] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.000s)
[92.197206] Starting dynamic subtest: pipe-D-HDMI-A-4
[92.213164] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.000s)
[92.216137] Subtest ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[92.383185] [015/156] (938s left) kms_frontbuffer_tracking (hdr-rgb565-draw-blt)
[93.880952] Starting subtest: hdr-rgb565-draw-blt
[93.883969] Subtest hdr-rgb565-draw-blt: SKIP (0.000s)
[94.025211] [016/156] (937s left) kms_pm_rpm (legacy-planes-dpms)
[97.973811] Starting subtest: legacy-planes-dpms
[97.975680] Starting dynamic subtest: plane-65
[105.911742] Dynamic subtest plane-65: SUCCESS (7.936s)
[105.965299] Starting dynamic subtest: plane-95
[114.958121] Dynamic subtest plane-95: SUCCESS (9.044s)
[115.010221] Starting dynamic subtest: plane-125
[122.041746] Dynamic subtest plane-125: SUCCESS (7.081s)
[122.094457] Starting dynamic subtest: plane-135
[129.007120] Dynamic subtest plane-135: SUCCESS (6.963s)
[129.059128] Starting dynamic subtest: plane-145
[135.987245] Dynamic subtest plane-145: SUCCESS (6.978s)
[136.039593] Starting dynamic subtest: plane-155
[142.952876] Dynamic subtest plane-155: SUCCESS (6.963s)
[142.955837] Subtest legacy-planes-dpms: SUCCESS (44.978s)
[143.977419] [017/156] (887s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[144.032949] Starting subtest: x-tiled-to-vebox-y-tiled
[144.034618] Starting dynamic subtest: smem
[144.116162] Dynamic subtest smem: SUCCESS (0.083s)
[144.127166] Starting dynamic subtest: lmem0
[144.874687] Dynamic subtest lmem0: SUCCESS (0.755s)
[144.876228] Subtest x-tiled-to-vebox-y-tiled: SUCCESS (0.839s)
[145.136537] [018/156] (886s left) sysfs_heartbeat_interval (invalid)
[145.188870] Starting subtest: invalid
[145.190348] Starting dynamic subtest: vecs0
[145.191890] Dynamic subtest vecs0: SUCCESS (0.001s)
[145.202903] Starting dynamic subtest: vcs0
[145.204449] Dynamic subtest vcs0: SUCCESS (0.000s)
[145.215119] Starting dynamic subtest: bcs0
[145.216810] Dynamic subtest bcs0: SUCCESS (0.000s)
[145.227581] Starting dynamic subtest: rcs0
[145.229212] Dynamic subtest rcs0: SUCCESS (0.000s)
[145.241222] Starting dynamic subtest: vcs1
[145.242709] Dynamic subtest vcs1: SUCCESS (0.000s)
[145.244156] Subtest invalid: SUCCESS (0.005s)
[145.303739] [019/156] (886s left) kms_frontbuffer_tracking (fbc-stridechange)
[146.802831] Starting subtest: fbc-stridechange
[148.840976] Subtest fbc-stridechange: SUCCESS (2.042s)
[149.131859] [020/156] (882s left) gem_ctx_param (basic-default)
[149.191856] Starting subtest: basic-default
[149.193701] Subtest basic-default: SUCCESS (0.000s)
[149.312091] [021/156] (882s left) kms_psr (fbc-pr-primary-mmap-gtt)
[150.802240] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s)
[150.881924] [022/156] (880s left) i915_pm_freq_mult (media-freq)
[150.930433] Starting subtest: media-freq
[150.932139] Starting dynamic subtest: gt0
[150.935662] Dynamic subtest gt0: SKIP (0.000s)
[150.938958] Subtest media-freq: SKIP (0.001s)
[151.057642] [023/156] (880s left) prime_busy (before-wait)
[151.110221] Starting subtest: before-wait
[151.116174] Starting dynamic subtest: rcs0
[151.158380] Dynamic subtest rcs0: SUCCESS (0.042s)
[151.169900] Starting dynamic subtest: bcs0
[151.199021] Dynamic subtest bcs0: SUCCESS (0.040s)
[151.212615] Starting dynamic subtest: vcs0
[151.241185] Dynamic subtest vcs0: SUCCESS (0.041s)
[151.265063] Starting dynamic subtest: vcs1
[151.295468] Dynamic subtest vcs1: SUCCESS (0.043s)
[151.323269] Starting dynamic subtest: vecs0
[151.328354] Dynamic subtest vecs0: SUCCESS (0.042s)
[151.440131] Subtest before-wait: SUCCESS (0.327s)
[151.597360] [024/156] (880s left) gem_exec_gttfill (engines)
[151.659242] Starting subtest: engines
[151.660837] Starting dynamic subtest: rcs0
[179.232233] Dynamic subtest rcs0: SUCCESS (27.572s)
[179.244690] Starting dynamic subtest: bcs0
[206.980213] Dynamic subtest bcs0: SUCCESS (27.749s)
[206.991918] Starting dynamic subtest: vcs0
[234.821339] Dynamic subtest vcs0: SUCCESS (27.841s)
[234.833038] Starting dynamic subtest: vcs1
[262.622332] Dynamic subtest vcs1: SUCCESS (27.800s)
[262.634369] Starting dynamic subtest: vecs0
[290.415473] Dynamic subtest vecs0: SUCCESS (27.793s)
[290.417166] Subtest engines: SUCCESS (138.758s)
[290.736826] [025/156] (741s left) syncobj_timeline (etime-multi-wait-all-submitted)
[290.846944] Starting subtest: etime-multi-wait-all-submitted
[290.957508] Subtest etime-multi-wait-all-submitted: SUCCESS (0.107s)
[291.066043] [026/156] (740s left) gem_ctx_param (get-priority-new-ctx)
[291.116133] Starting subtest: get-priority-new-ctx
[291.117509] Subtest get-priority-new-ctx: SUCCESS (0.001s)
[291.218559] [027/156] (740s left) kms_big_fb (linear-32bpp-rotate-90)
[292.742493] Starting subtest: linear-32bpp-rotate-90
[292.839730] Subtest linear-32bpp-rotate-90: SKIP (0.095s)
[292.985659] [028/156] (739s left) gem_lmem_swapping (heavy-verify-multi-ccs)
[297.400984] Starting subtest: heavy-verify-multi-ccs
[297.402928] Starting dynamic subtest: lmem0
[297.405947] Dynamic subtest lmem0: SKIP (0.000s)
[297.409528] Subtest heavy-verify-multi-ccs: SKIP (0.001s)
[300.529560] [FACT gem_lmem_swapping (heavy-verify-multi-ccs)] deleted: kernel.kmod_is_loaded.i915: true
[300.532638] [029/156] (731s left) gem_ctx_shared (disjoint-timelines)
[303.839900] Starting subtest: disjoint-timelines
[303.919845] Subtest disjoint-timelines: SUCCESS (0.081s)
[304.268115] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.i915: true
[304.268819] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.vgem: true
[304.274580] [030/156] (728s left) kms_psr (fbc-psr2-cursor-plane-move)
[305.816316] Subtest fbc-psr2-cursor-plane-move: SKIP (0.000s)
[305.894606] [031/156] (726s left) gem_bad_reloc (negative-reloc-bltcopy)
[305.951033] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[306.057304] [032/156] (726s left) kms_chamelium_hpd (hdmi-hpd)
[307.592377] Subtest hdmi-hpd: SKIP (0.000s)
[307.720364] [033/156] (724s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-fully-sf)
[309.217535] Subtest pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[309.300773] [034/156] (723s left) gem_eio (wait-10ms)
[309.439705] Starting subtest: wait-10ms
[309.823701] Subtest wait-10ms: SUCCESS (0.384s)
[311.415238] [035/156] (721s left) kms_content_protection (legacy-hdcp14)
[312.999272] Starting subtest: legacy-hdcp14
[313.009221] Subtest legacy-hdcp14: SKIP (0.002s)
[313.171225] [036/156] (719s left) syncobj_wait (reset-signaled)
[313.229928] Starting subtest: reset-signaled
[313.231651] Subtest reset-signaled: SUCCESS (0.000s)
[313.349616] [037/156] (719s left) gem_ctx_isolation (dirty-switch)
[313.407530] Starting subtest: dirty-switch
[313.409179] Starting dynamic subtest: rcs0
[314.227246] Dynamic subtest rcs0: SUCCESS (0.819s)
[314.241015] Starting dynamic subtest: bcs0
[315.077395] Dynamic subtest bcs0: SUCCESS (0.849s)
[315.090889] Starting dynamic subtest: vcs0
[315.917066] Dynamic subtest vcs0: SUCCESS (0.839s)
[315.930489] Starting dynamic subtest: vcs1
[316.756970] Dynamic subtest vcs1: SUCCESS (0.839s)
[316.770606] Starting dynamic subtest: vecs0
[317.608411] Dynamic subtest vecs0: SUCCESS (0.851s)
[317.609792] Subtest dirty-switch: SUCCESS (4.202s)
[317.913837] [038/156] (715s left) gem_flink_basic (bad-open)
[317.963811] Starting subtest: bad-open
[317.965253] Subtest bad-open: SUCCESS (0.000s)
[318.076820] [039/156] (714s left) gem_caching (writes)
[318.129140] Starting subtest: writes
[323.354393] Subtest writes: SUCCESS (5.225s)
[323.504156] [040/156] (709s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[324.994078] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[324.995416] Starting dynamic subtest: pipe-A
[325.787056] Dynamic subtest pipe-A: SUCCESS (0.792s)
[325.804223] Starting dynamic subtest: pipe-B
[326.560140] Dynamic subtest pipe-B: SUCCESS (0.772s)
[326.581052] Starting dynamic subtest: pipe-C
[327.349728] Dynamic subtest pipe-C: SUCCESS (0.788s)
[327.364383] Starting dynamic subtest: pipe-D
[328.122768] Dynamic subtest pipe-D: SUCCESS (0.772s)
[328.124568] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (3.129s)
[328.342112] [041/156] (704s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[328.394587] Starting subtest: single-wait-all-for-submit-available-submitted
[328.395898] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[328.572732] [042/156] (704s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-render)
[330.073218] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-render
[330.076904] Subtest fbchdr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[330.243273] [043/156] (703s left) kms_pm_rpm (system-suspend-idle)
[334.097583] Starting subtest: system-suspend-idle
[353.431787] Subtest system-suspend-idle: SUCCESS (4.393s)
[377.541656] [044/156] (678s left) kms_atomic_transition (modeset-transition)
[379.029125] Starting subtest: modeset-transition
[379.030663] Starting dynamic subtest: 1x-outputs
[381.135325] Dynamic subtest 1x-outputs: SUCCESS (2.145s)
[381.136986] Subtest modeset-transition: SUCCESS (2.146s)
[381.345902] [045/156] (675s left) kms_frontbuffer_tracking (pipe-fbc-rte)
[382.822782] Starting subtest: pipe-fbc-rte
[382.832013] Subtest pipe-fbc-rte: SKIP (0.000s)
[383.000893] [046/156] (673s left) gem_spin_batch (resubmit-all)
[383.060451] Starting subtest: resubmit-all
[383.062036] Starting dynamic subtest: rcs0
[383.085355] Dynamic subtest rcs0: SUCCESS (0.024s)
[383.102252] Starting dynamic subtest: bcs0
[383.104054] Dynamic subtest bcs0: SUCCESS (0.002s)
[383.118749] Starting dynamic subtest: vcs0
[383.120419] Dynamic subtest vcs0: SUCCESS (0.003s)
[383.134549] Starting dynamic subtest: vcs1
[383.136336] Dynamic subtest vcs1: SUCCESS (0.002s)
[383.150473] Starting dynamic subtest: vecs0
[383.152247] Dynamic subtest vecs0: SUCCESS (0.002s)
[383.154057] Subtest resubmit-all: SUCCESS (0.040s)
[383.411284] [047/156] (673s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff)
[384.895086] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff
[384.898297] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[385.084660] [048/156] (671s left) syncobj_basic (illegal-fd-to-handle)
[385.134406] Starting subtest: illegal-fd-to-handle
[385.136244] Subtest illegal-fd-to-handle: SUCCESS (0.000s)
[385.276536] [049/156] (671s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite)
[386.762314] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite
[386.765333] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[386.951570] [050/156] (670s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[388.451378] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[389.715825] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.268s)
[389.903373] [051/156] (667s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[389.955184] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[390.059079] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.101s)
[390.181202] [052/156] (667s left) api_intel_bb (misplaced-blitter)
[390.230509] Starting subtest: misplaced-blitter
[390.252176] Subtest misplaced-blitter: SUCCESS (0.022s)
[390.467924] [053/156] (666s left) kms_atomic_interruptible (universal-setplane-cursor)
[391.955433] Starting subtest: universal-setplane-cursor
[391.957244] Starting dynamic subtest: pipe-A-HDMI-A-4
[398.176674] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (6.239s)
[398.178344] Subtest universal-setplane-cursor: SUCCESS (6.241s)
[398.365010] [054/156] (659s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[399.873015] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[399.875702] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[400.056577] [055/156] (657s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[401.551976] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[401.555702] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[401.754065] [056/156] (655s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[403.244011] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[403.245696] Starting dynamic subtest: pipe-A-valid-mode
[404.601604] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.357s)
[404.603266] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SUCCESS (1.359s)
[404.812848] [057/156] (653s left) gem_pxp (create-protected-buffer)
[407.391940] Starting subtest: create-protected-buffer
[407.394942] Subtest create-protected-buffer: SKIP (0.000s)
[407.511595] [058/156] (650s left) gem_fence_thrash (bo-write-verify-threaded-x)
[407.558283] Starting subtest: bo-write-verify-threaded-x
[407.562893] Subtest bo-write-verify-threaded-x: SKIP (0.003s)
[407.649679] [059/156] (650s left) gen9_exec_parse (allowed-single)
[407.703676] Subtest allowed-single: SKIP (0.000s)
[407.781612] [060/156] (650s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[409.278933] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[409.281598] Subtest psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[409.435914] [061/156] (648s left) gem_exec_balancer (bonded-semaphore)
[409.500864] Starting subtest: bonded-semaphore
[409.503982] Subtest bonded-semaphore: SKIP (0.000s)
[410.020985] [062/156] (648s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping)
[411.516912] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping
[411.548363] Starting dynamic subtest: pipe-A-plane-0
[412.065834] Dynamic subtest pipe-A-plane-0: SUCCESS (0.516s)
[412.120051] Starting dynamic subtest: pipe-A-plane-3
[412.381946] Dynamic subtest pipe-A-plane-3: SUCCESS (0.311s)
[412.437036] Starting dynamic subtest: pipe-A-plane-5
[412.714294] Dynamic subtest pipe-A-plane-5: SUCCESS (0.329s)
[412.895929] Starting dynamic subtest: pipe-B-plane-0
[413.283038] Dynamic subtest pipe-B-plane-0: SUCCESS (0.400s)
[413.328686] Starting dynamic subtest: pipe-B-plane-3
[413.599483] Dynamic subtest pipe-B-plane-3: SUCCESS (0.313s)
[413.665529] Starting dynamic subtest: pipe-B-plane-5
[413.932212] Dynamic subtest pipe-B-plane-5: SUCCESS (0.330s)
[413.987476] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping: SUCCESS (2.472s)
[414.168482] [063/156] (644s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc)
[415.670069] Starting subtest: fbc-rgb565-draw-mmap-wc
[415.673205] Subtest fbc-rgb565-draw-mmap-wc: SKIP (0.000s)
[415.848751] [064/156] (642s left) kms_pwrite_crc (basic)
[417.340336] Starting subtest: basic
[417.984768] Subtest basic: SUCCESS (0.644s)
[418.209055] [065/156] (640s left) gem_flink_basic (bad-flink)
[418.260771] Starting subtest: bad-flink
[418.262537] Subtest bad-flink: SUCCESS (0.000s)
[418.372793] [066/156] (640s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-move)
[419.873028] Starting subtest: hdr-2p-primscrn-spr-indfb-move
[419.876253] Subtest hdr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[420.044295] [067/156] (638s left) gem_ctx_persistence (legacy-engines-persistence)
[420.099283] Starting subtest: legacy-engines-persistence
[420.100934] Starting dynamic subtest: render
[420.882328] Dynamic subtest render: SUCCESS (0.782s)
[420.903632] Starting dynamic subtest: bsd1
[421.647160] Dynamic subtest bsd1: SUCCESS (0.762s)
[421.665427] Starting dynamic subtest: bsd2
[422.416247] Dynamic subtest bsd2: SUCCESS (0.766s)
[422.435948] Starting dynamic subtest: blt
[423.181354] Dynamic subtest blt: SUCCESS (0.759s)
[423.200670] Starting dynamic subtest: vebox
[423.948372] Dynamic subtest vebox: SUCCESS (0.762s)
[423.954103] Subtest legacy-engines-persistence: SUCCESS (3.853s)
[424.110045] [068/156] (634s left) gen7_exec_parse (basic-allowed)
[424.165189] Subtest basic-allowed: SKIP (0.000s)
[424.245874] [069/156] (634s left) gem_exec_schedule (reorder-wide)
[424.451499] Subtest reorder-wide: SKIP (0.000s)
[424.700311] [070/156] (634s left) kms_frontbuffer_tracking (psrhdr-slowdraw)
[426.194883] Starting subtest: psrhdr-slowdraw
[426.197763] Subtest psrhdr-slowdraw: SKIP (0.000s)
[426.387095] [071/156] (632s left) perf (non-zero-reason)
[426.537752] Starting subtest: non-zero-reason
[426.539270] Starting dynamic subtest: 0-rcs0
[437.596104] Dynamic subtest 0-rcs0: SUCCESS (11.060s)
[437.598030] Subtest non-zero-reason: SUCCESS (11.061s)
[437.678643] [072/156] (621s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-pwrite)
[439.178743] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-pwrite
[439.181656] Subtest fbchdr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[439.360792] [073/156] (619s left) i915_drm_fdinfo (all-busy-check-all)
[439.433433] Starting subtest: all-busy-check-all
[439.452295] Subtest all-busy-check-all: SKIP (0.017s)
[439.631783] [074/156] (619s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[441.177254] Starting subtest: psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[441.180010] Subtest psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[441.362055] [075/156] (618s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[442.853365] Starting subtest: compare-crc-sanitycheck-nv12
[442.855089] Starting dynamic subtest: pipe-A-HDMI-A-4
[443.482093] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.626s)
[443.498646] Starting dynamic subtest: pipe-B-HDMI-A-4
[444.016079] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (0.533s)
[444.036783] Starting dynamic subtest: pipe-C-HDMI-A-4
[444.532334] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (0.512s)
[444.551809] Starting dynamic subtest: pipe-D-HDMI-A-4
[445.073905] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (0.538s)
[445.075448] Subtest compare-crc-sanitycheck-nv12: SUCCESS (2.221s)
[445.288801] [076/156] (614s left) gem_exec_reloc (basic-write-gtt)
[445.345453] Subtest basic-write-gtt: SKIP (0.000s)
[445.440811] [077/156] (614s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[447.244866] Starting subtest: cursor-rapid-movement-max-size
[447.249098] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[447.462105] [078/156] (612s left) kms_color_pipeline (plane-lut1d)
[448.953019] Starting subtest: plane-lut1d
[449.164608] Starting dynamic subtest: pipe-A-plane-0
[449.422089] Dynamic subtest pipe-A-plane-0: SUCCESS (0.264s)
[449.448158] Starting dynamic subtest: pipe-A-plane-1
[449.617365] Dynamic subtest pipe-A-plane-1: SUCCESS (0.181s)
[449.653160] Starting dynamic subtest: pipe-A-plane-2
[449.807696] Dynamic subtest pipe-A-plane-2: SUCCESS (0.193s)
[450.140813] Starting dynamic subtest: pipe-B-plane-0
[450.300089] Dynamic subtest pipe-B-plane-0: SUCCESS (0.197s)
[450.342972] Starting dynamic subtest: pipe-B-plane-1
[450.496614] Dynamic subtest pipe-B-plane-1: SUCCESS (0.192s)
[450.512187] Starting dynamic subtest: pipe-B-plane-2
[450.664182] Dynamic subtest pipe-B-plane-2: SUCCESS (0.165s)
[450.970994] Starting dynamic subtest: pipe-C-plane-0
[451.134345] Dynamic subtest pipe-C-plane-0: SUCCESS (0.197s)
[451.168814] Starting dynamic subtest: pipe-C-plane-1
[451.346077] Dynamic subtest pipe-C-plane-1: SUCCESS (0.209s)
[451.373644] Starting dynamic subtest: pipe-C-plane-2
[451.543847] Dynamic subtest pipe-C-plane-2: SUCCESS (0.195s)
[451.836524] Starting dynamic subtest: pipe-D-plane-0
[452.004319] Dynamic subtest pipe-D-plane-0: SUCCESS (0.180s)
[452.028437] Starting dynamic subtest: pipe-D-plane-1
[452.202723] Dynamic subtest pipe-D-plane-1: SUCCESS (0.196s)
[452.243624] Starting dynamic subtest: pipe-D-plane-2
[452.419429] Dynamic subtest pipe-D-plane-2: SUCCESS (0.209s)
[452.492982] Subtest plane-lut1d: SUCCESS (3.538s)
[452.697509] [079/156] (607s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[454.209384] Starting subtest: yf-tiled-16bpp-rotate-180
[454.211983] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[454.405441] [080/156] (605s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[455.915697] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[455.919075] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[456.162090] [081/156] (603s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[457.658395] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[457.661631] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[457.821799] [082/156] (602s left) gem_exec_reloc (basic-wc-read)
[457.879290] Subtest basic-wc-read: SKIP (0.000s)
[457.991026] [083/156] (602s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[459.483904] Starting subtest: short-busy-flip-before-cursor-toggle
[459.489373] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[459.610953] [084/156] (600s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[461.115673] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[461.134718] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.017s)
[461.270021] [085/156] (599s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[462.790490] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[462.793326] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[462.952509] [086/156] (597s left) kms_draw_crc (draw-method-render)
[464.446673] Starting subtest: draw-method-render
[464.448180] Starting dynamic subtest: xrgb8888-untiled
[464.863440] Dynamic subtest xrgb8888-untiled: SUCCESS (0.416s)
[464.906304] Starting dynamic subtest: rgb565-untiled
[465.228688] Dynamic subtest rgb565-untiled: SUCCESS (0.356s)
[465.278856] Starting dynamic subtest: xrgb2101010-untiled
[465.578791] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.342s)
[465.632500] Starting dynamic subtest: xbgr16161616f-untiled
[465.928064] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.342s)
[465.978146] Starting dynamic subtest: xrgb8888-xtiled
[466.126643] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.192s)
[466.156563] Starting dynamic subtest: rgb565-xtiled
[466.311343] Dynamic subtest rgb565-xtiled: SUCCESS (0.181s)
[466.362715] Starting dynamic subtest: xrgb2101010-xtiled
[466.494664] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.176s)
[466.547422] Starting dynamic subtest: xbgr16161616f-xtiled
[466.693003] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.192s)
[466.711225] Starting dynamic subtest: xrgb8888-ytiled
[466.861241] Dynamic subtest xrgb8888-ytiled: SUCCESS (0.166s)
[466.901405] Starting dynamic subtest: rgb565-ytiled
[467.044571] Dynamic subtest rgb565-ytiled: SUCCESS (0.174s)
[467.096466] Starting dynamic subtest: xrgb2101010-ytiled
[467.227774] Dynamic subtest xrgb2101010-ytiled: SUCCESS (0.175s)
[467.280972] Starting dynamic subtest: xbgr16161616f-ytiled
[467.427803] Dynamic subtest xbgr16161616f-ytiled: SUCCESS (0.192s)
[467.439235] Subtest draw-method-render: SUCCESS (2.987s)
[467.693102] [087/156] (593s left) kms_cursor_crc (cursor-sliding-256x85)
[469.424294] Starting subtest: cursor-sliding-256x85
[469.431330] Starting dynamic subtest: pipe-A-HDMI-A-4
[473.885219] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (4.452s)
[473.921926] Starting dynamic subtest: pipe-D-HDMI-A-4
[478.264120] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (4.352s)
[478.266055] Subtest cursor-sliding-256x85: SUCCESS (8.834s)
[478.603309] [088/156] (582s left) kms_cursor_crc (cursor-onscreen-256x256)
[480.310975] Starting subtest: cursor-onscreen-256x256
[480.318503] Starting dynamic subtest: pipe-A-HDMI-A-4
[483.079105] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (2.760s)
[483.115397] Starting dynamic subtest: pipe-D-HDMI-A-4
[485.754932] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (2.652s)
[485.756608] Subtest cursor-onscreen-256x256: SUCCESS (5.442s)
[486.168501] [089/156] (574s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render)
[487.669363] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render
[487.672957] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[487.859937] [090/156] (573s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[489.358702] Starting subtest: fbcpsr-slowdraw
[489.362013] Subtest fbcpsr-slowdraw: SKIP (0.000s)
[489.549750] [091/156] (571s left) kms_lease (cursor-implicit-plane)
[491.048105] Starting subtest: cursor-implicit-plane
[491.049668] Starting dynamic subtest: pipe-A-HDMI-A-4
[491.473291] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.422s)
[491.505639] Starting dynamic subtest: pipe-B-HDMI-A-4
[491.721244] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (0.242s)
[491.740374] Starting dynamic subtest: pipe-C-HDMI-A-4
[491.948001] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (0.223s)
[491.965785] Starting dynamic subtest: pipe-D-HDMI-A-4
[492.161585] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (0.211s)
[492.163107] Subtest cursor-implicit-plane: SUCCESS (1.114s)
[492.384027] [092/156] (568s left) syncobj_wait (invalid-reset-zero-handles)
[492.435501] Starting subtest: invalid-reset-zero-handles
[492.437268] Subtest invalid-reset-zero-handles: SUCCESS (0.000s)
[492.573751] [093/156] (568s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render)
[494.073244] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render
[494.076430] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[494.234282] [094/156] (567s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[495.748071] Starting subtest: yf-tiled-64bpp-rotate-90
[495.751118] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[495.930026] [095/156] (565s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc)
[497.428788] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc
[497.431810] Subtest fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[497.628478] [096/156] (564s left) gem_exec_fence (long-history)
[498.049463] Starting subtest: long-history
[504.126554] Subtest long-history: SUCCESS (6.074s)
[504.517773] [097/156] (557s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt)
[506.015439] Starting subtest: psr-1p-primscrn-indfb-msflip-blt
[506.018736] Subtest psr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[506.206688] [098/156] (555s left) kms_ccs (bad-rotation-90-yf-tiled-ccs)
[507.701219] Starting subtest: bad-rotation-90-yf-tiled-ccs
[507.702654] Starting dynamic subtest: pipe-A-HDMI-A-4
[507.716662] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.000s)
[507.732595] Starting dynamic subtest: pipe-B-HDMI-A-4
[507.747225] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.000s)
[507.762444] Starting dynamic subtest: pipe-C-HDMI-A-4
[507.777116] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.000s)
[507.792331] Starting dynamic subtest: pipe-D-HDMI-A-4
[507.808783] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.000s)
[507.811859] Subtest bad-rotation-90-yf-tiled-ccs: SKIP (0.010s)
[507.984906] [099/156] (553s left) kms_cursor_legacy (long-nonblocking-modeset-vs-cursor-atomic)
[509.472973] Starting subtest: long-nonblocking-modeset-vs-cursor-atomic
[511.448697] Subtest long-nonblocking-modeset-vs-cursor-atomic: SUCCESS (1.975s)
[511.654295] [100/156] (550s left) kms_chamelium_audio (dp-audio-edid)
[513.188265] Subtest dp-audio-edid: SKIP (0.000s)
[513.321351] [101/156] (548s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[514.813406] Starting subtest: addfb25-yf-tiled-legacy
[514.814942] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[515.010040] [102/156] (547s left) kms_frontbuffer_tracking (hdr-1p-primscrn-indfb-pgflip-blt)
[516.523269] Starting subtest: hdr-1p-primscrn-indfb-pgflip-blt
[516.526241] Subtest hdr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[516.698814] [103/156] (545s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff)
[518.196798] Starting subtest: psr-2p-primscrn-cur-indfb-onoff
[518.199878] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[518.350181] [104/156] (544s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw)
[519.862640] Starting subtest: fbc-2p-pri-indfb-multidraw
[519.865882] Subtest fbc-2p-pri-indfb-multidraw: SKIP (0.000s)
[520.035117] [105/156] (542s left) kms_sequence (queue-busy)
[521.527390] Starting subtest: queue-busy
[521.528841] Starting dynamic subtest: pipe-A-HDMI-A-4
[524.121227] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (2.593s)
[524.137271] Starting dynamic subtest: pipe-B-HDMI-A-4
[526.545682] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (2.422s)
[526.562365] Starting dynamic subtest: pipe-C-HDMI-A-4
[528.967923] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (2.420s)
[528.985147] Starting dynamic subtest: pipe-D-HDMI-A-4
[531.400504] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (2.432s)
[531.401953] Subtest queue-busy: SUCCESS (9.877s)
[531.620981] [106/156] (531s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[533.113279] Starting subtest: plane-upscale-20x20-with-rotation
[533.114683] Starting dynamic subtest: pipe-A
[533.544779] Dynamic subtest pipe-A: SKIP (0.430s)
[533.560476] Starting dynamic subtest: pipe-B
[533.970772] Dynamic subtest pipe-B: SKIP (0.421s)
[533.986353] Starting dynamic subtest: pipe-C
[534.391763] Dynamic subtest pipe-C: SKIP (0.421s)
[534.407361] Starting dynamic subtest: pipe-D
[534.816614] Dynamic subtest pipe-D: SKIP (0.424s)
[534.820230] Subtest plane-upscale-20x20-with-rotation: SKIP (1.702s)
[535.021206] [107/156] (527s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-pwrite)
[536.522869] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-pwrite
[536.525680] Subtest psrhdr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[536.670514] [108/156] (526s left) gem_exec_reloc (basic-write-wc)
[536.726334] Subtest basic-write-wc: SKIP (0.000s)
[536.832740] [109/156] (526s left) syncobj_timeline (reset-signaled)
[536.885300] Starting subtest: reset-signaled
[536.886843] Subtest reset-signaled: SUCCESS (0.000s)
[537.019178] [110/156] (525s left) kms_psr (pr-cursor-plane-move)
[538.542553] Subtest pr-cursor-plane-move: SKIP (0.000s)
[538.629613] [111/156] (524s left) syncobj_wait (invalid-wait-illegal-handle)
[538.678941] Starting subtest: invalid-wait-illegal-handle
[538.680743] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[538.824304] [112/156] (524s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[540.324429] Starting subtest: extended-pageflip-modeset-hang-oldfb
[540.325911] Starting dynamic subtest: pipe-A
[548.065821] Dynamic subtest pipe-A: SUCCESS (7.743s)
[548.084662] Starting dynamic subtest: pipe-D
[555.797411] Dynamic subtest pipe-D: SUCCESS (7.728s)
[555.799108] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (15.479s)
[556.034102] [113/156] (507s left) kms_psr (psr2-primary-blt)
[557.527785] Subtest psr2-primary-blt: SKIP (0.000s)
[557.619923] [114/156] (505s left) gem_softpin (evict-snoop-interruptible)
[557.678529] Starting subtest: evict-snoop-interruptible
[557.681592] Subtest evict-snoop-interruptible: SKIP (0.000s)
[557.795253] [115/156] (505s left) kms_force_connector_basic (force-load-detect)
[557.927762] Starting subtest: force-load-detect
[557.930567] Subtest force-load-detect: SKIP (0.000s)
[558.875598] [116/156] (504s left) kms_chamelium_edid (hdmi-mode-timings)
[560.411364] Subtest hdmi-mode-timings: SKIP (0.000s)
[560.543722] [117/156] (503s left) syncobj_timeline (invalid-signal-zero-handles)
[560.594270] Starting subtest: invalid-signal-zero-handles
[560.596246] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[560.718664] [118/156] (502s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt)
[562.256039] Starting subtest: fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt
[562.259565] Subtest fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[562.445507] [119/156] (501s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[563.943992] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[563.947240] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[564.131515] [120/156] (499s left) gem_mmap_gtt (fault-concurrent-x)
[564.184378] Subtest fault-concurrent-X: SKIP (0.000s)
[564.277050] [121/156] (499s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[564.349776] Starting subtest: y-tiled-to-vebox-y-tiled
[564.351466] Starting dynamic subtest: smem
[564.436012] Dynamic subtest smem: SUCCESS (0.085s)
[564.451787] Starting dynamic subtest: lmem0
[565.192476] Dynamic subtest lmem0: SUCCESS (0.752s)
[565.193941] Subtest y-tiled-to-vebox-y-tiled: SUCCESS (0.841s)
[565.494976] [122/156] (498s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-shrfb-plflip-blt)
[566.995300] Starting subtest: psrhdr-2p-scndscrn-shrfb-plflip-blt
[566.999089] Subtest psrhdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[567.179533] [123/156] (497s left) vgem_basic (debugfs)
[567.241010] Starting subtest: debugfs
[567.242924] Subtest debugfs: SUCCESS (0.000s)
[567.346372] [124/156] (496s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[568.849422] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[568.851234] Starting dynamic subtest: pipe-A-valid-mode
[570.151312] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.300s)
[570.153010] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SUCCESS (1.302s)
[570.371486] [125/156] (494s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite)
[571.872092] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite
[571.874798] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[572.106725] [126/156] (492s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[573.604407] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[573.607702] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[573.812709] [127/156] (490s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt)
[575.314513] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt
[575.317942] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[575.498389] [128/156] (489s left) kms_vblank (wait-forked-hang)
[576.991523] Starting subtest: wait-forked-hang
[576.992989] Starting dynamic subtest: pipe-A-HDMI-A-4
[603.429824] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (26.441s)
[603.450769] Starting dynamic subtest: pipe-D-HDMI-A-4
[628.409259] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (24.968s)
[628.410951] Subtest wait-forked-hang: SUCCESS (51.418s)
[628.623728] [129/156] (436s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[630.115181] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[630.116844] Starting dynamic subtest: pipe-A-valid-mode
[632.673534] Dynamic subtest pipe-A-valid-mode: SUCCESS (2.556s)
[632.675053] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SUCCESS (2.558s)
[632.868270] [130/156] (432s left) gem_ctx_exec (basic-invalid-context)
[632.921376] Starting subtest: basic-invalid-context
[632.925744] Subtest basic-invalid-context: SUCCESS (0.005s)
[633.124113] [131/156] (431s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-indfb-msflip-blt)
[634.621589] Starting subtest: fbcpsrhdr-1p-primscrn-indfb-msflip-blt
[634.624972] Subtest fbcpsrhdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[634.785382] [132/156] (430s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[636.280025] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (0.000s)
[636.359496] [133/156] (428s left) gem_ppgtt (blt-vs-render-ctxn)
[636.414566] Starting subtest: blt-vs-render-ctxN
[669.167745] Subtest blt-vs-render-ctxN: SUCCESS (32.749s)
[669.394233] [134/156] (396s left) gem_exec_reloc (basic-gtt-cpu-noreloc)
[669.511792] Subtest basic-gtt-cpu-noreloc: SKIP (0.000s)
[669.621637] [135/156] (395s left) gem_softpin (invalid)
[669.676987] Starting subtest: invalid
[669.691891] Subtest invalid: SUCCESS (0.012s)
[669.879120] [136/156] (395s left) gem_exec_capture (capture)
[669.936892] Starting subtest: capture
[669.938394] Starting dynamic subtest: rcs0-smem
[670.841957] Dynamic subtest rcs0-smem: SUCCESS (0.905s)
[670.859215] Starting dynamic subtest: rcs0-lmem0
[671.725236] Dynamic subtest rcs0-lmem0: SUCCESS (0.880s)
[671.740846] Starting dynamic subtest: bcs0-smem
[673.095971] Dynamic subtest bcs0-smem: SUCCESS (1.371s)
[673.111990] Starting dynamic subtest: bcs0-lmem0
[674.123340] Dynamic subtest bcs0-lmem0: SUCCESS (1.025s)
[674.139228] Starting dynamic subtest: vcs0-smem
[674.982061] Dynamic subtest vcs0-smem: SUCCESS (0.857s)
[674.997985] Starting dynamic subtest: vcs0-lmem0
[675.841729] Dynamic subtest vcs0-lmem0: SUCCESS (0.856s)
[675.857350] Starting dynamic subtest: vcs1-smem
[676.702409] Dynamic subtest vcs1-smem: SUCCESS (0.862s)
[676.718319] Starting dynamic subtest: vcs1-lmem0
[677.561214] Dynamic subtest vcs1-lmem0: SUCCESS (0.855s)
[677.577124] Starting dynamic subtest: vecs0-smem
[678.986236] Dynamic subtest vecs0-smem: FAIL (1.395s)
[679.001138] Starting dynamic subtest: vecs0-lmem0
[679.987333] Dynamic subtest vecs0-lmem0: FAIL (0.984s)
[679.989033] Subtest capture: FAIL (10.005s)
[680.109751] [137/156] (385s left) gem_tiled_blits (basic)
[680.164364] Subtest basic: SKIP (0.000s)
[680.242867] [138/156] (385s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt)
[681.749888] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt
[681.753040] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[681.965637] [139/156] (383s left) gem_mmap_offset (partial-remap)
[682.016347] Starting subtest: partial-remap
[682.017988] Starting dynamic subtest: lmem0
[682.023784] Dynamic subtest lmem0: SUCCESS (0.006s)
[682.040108] Starting dynamic subtest: smem0
[682.043986] Dynamic subtest smem0: SUCCESS (0.005s)
[682.045927] Subtest partial-remap: SUCCESS (0.014s)
[682.183720] [140/156] (383s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-blt)
[683.682883] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-blt
[683.685930] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s)
[683.835896] [141/156] (382s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-blt)
[685.334825] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-blt
[685.339966] Subtest hdr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[685.544856] [142/156] (380s left) i915_power (sanity)
[685.594242] Starting subtest: sanity
[689.635917] Subtest sanity: SUCCESS (4.035s)
[689.784120] [143/156] (376s left) gem_exec_schedule (preempt-queue)
[689.866420] Subtest preempt-queue: SKIP (0.000s)
[690.270113] [144/156] (376s left) kms_cursor_legacy (cursorb-vs-flipa-legacy)
[691.760085] Starting subtest: cursorB-vs-flipA-legacy
[691.765926] Subtest cursorB-vs-flipA-legacy: SKIP (0.002s)
[691.886809] [145/156] (374s left) i915_pm_rps (reset)
[691.950673] Starting subtest: reset
[696.678371] Subtest reset: SUCCESS (4.729s)
[696.831920] [146/156] (369s left) kms_psr (fbc-psr-cursor-plane-onoff)
[698.329376] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.000s)
[698.408968] [147/156] (368s left) kms_setmode (clone-exclusive-crtc)
[698.460969] Starting subtest: clone-exclusive-crtc
[698.465828] Subtest clone-exclusive-crtc: SKIP (0.000s)
[698.595446] [148/156] (368s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[700.091651] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[700.094761] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[700.284714] [149/156] (366s left) kms_flip (flip-vs-rmfb)
[701.774424] Starting subtest: flip-vs-rmfb
[701.782506] Starting dynamic subtest: A-HDMI-A4
[709.565406] Dynamic subtest A-HDMI-A4: SUCCESS (7.783s)
[709.584580] Starting dynamic subtest: B-HDMI-A4
[717.292601] Dynamic subtest B-HDMI-A4: SUCCESS (7.726s)
[717.339247] Starting dynamic subtest: C-HDMI-A4
[725.036734] Dynamic subtest C-HDMI-A4: SUCCESS (7.740s)
[725.080985] Starting dynamic subtest: D-HDMI-A4
[732.796200] Dynamic subtest D-HDMI-A4: SUCCESS (7.755s)
[732.798498] Subtest flip-vs-rmfb: SUCCESS (31.028s)
[733.056713] [150/156] (333s left) gem_exec_whisper (basic-contexts-all)
[733.121804] Starting subtest: basic-contexts-all
[740.347232] Subtest basic-contexts-all: SUCCESS (7.223s)
[740.637419] [151/156] (326s left) dmabuf_sync_file (export-basic)
[740.701807] Starting subtest: export-basic
[740.703343] Subtest export-basic: SUCCESS (0.001s)
[740.812189] [152/156] (326s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[742.308263] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[742.311070] Subtest hdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[742.539828] [153/156] (324s left) gem_sync (basic-each)
[742.604243] Starting subtest: basic-each
[744.987860] Subtest basic-each: SUCCESS (2.386s)
[745.339931] [154/156] (322s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc)
[746.846688] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc
[746.848225] Starting dynamic subtest: pipe-A-HDMI-A-4
[746.865015] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.000s)
[746.882196] Starting dynamic subtest: pipe-B-HDMI-A-4
[746.901377] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.000s)
[746.917843] Starting dynamic subtest: pipe-C-HDMI-A-4
[746.935988] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.000s)
[746.952172] Starting dynamic subtest: pipe-D-HDMI-A-4
[746.970944] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.000s)
[746.974289] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.010s)
[747.138273] [155/156] (320s left) syncobj_wait (multi-wait-for-submit-submitted)
[747.191273] Starting subtest: multi-wait-for-submit-submitted
[747.297603] Subtest multi-wait-for-submit-submitted: SUCCESS (0.106s)
[747.484755] [156/156] (320s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[748.982953] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt
[748.986326] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[749.161771] 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.