[59.100540] Initializing watchdogs
[59.100588]   /dev/watchdog0
[59.116118] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[59.120623] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.123172] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.125227] [001/155] (960s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping)
[59.226845] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping
[59.265775] Starting dynamic subtest: pipe-A-plane-0
[59.577440] Dynamic subtest pipe-A-plane-0: SUCCESS (0.312s)
[59.584890] Starting dynamic subtest: pipe-A-plane-3
[59.810281] Dynamic subtest pipe-A-plane-3: SUCCESS (0.232s)
[60.935894] Starting dynamic subtest: pipe-B-plane-0
[61.227070] Dynamic subtest pipe-B-plane-0: SUCCESS (0.300s)
[61.234206] Starting dynamic subtest: pipe-B-plane-3
[61.460243] Dynamic subtest pipe-B-plane-3: SUCCESS (0.233s)
[61.779359] Subtest pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping: SUCCESS (2.554s)
[62.616676] [002/155] (957s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-cur-indfb-move)
[62.714842] Starting subtest: fbcpsrhdr-2p-primscrn-cur-indfb-move
[62.716896] Subtest fbcpsrhdr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[62.806271] [003/155] (956s left) gem_exec_fence (submit3)
[62.988950] Starting subtest: submit3
[62.991262] Subtest submit3: SKIP (0.000s)
[64.420570] [FACT gem_exec_fence (submit3)] new: kernel.kmod_is_loaded.vgem: true
[64.422993] [004/155] (955s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-msflip-blt)
[64.513795] Starting subtest: psr-2p-primscrn-shrfb-msflip-blt
[64.515886] Subtest psr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[64.616082] [005/155] (955s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[64.710328] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu
[68.806375] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (4.097s)
[70.018853] [006/155] (949s left) gem_exec_params (larger-than-life-batch)
[70.070076] Starting subtest: larger-than-life-batch
[72.451386] Subtest larger-than-life-batch: SUCCESS (2.381s)
[73.357367] [007/155] (946s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-blt)
[73.506359] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-blt
[77.224961] Subtest fbc-1p-primscrn-spr-indfb-draw-blt: SUCCESS (3.721s)
[78.430431] [008/155] (941s left) kms_big_fb (4-tiled-64bpp-rotate-90)
[78.532708] Starting subtest: 4-tiled-64bpp-rotate-90
[78.554171] Subtest 4-tiled-64bpp-rotate-90: SKIP (0.020s)
[78.666431] [009/155] (941s left) gem_create (hog-create)
[78.719783] Starting subtest: hog-create
[78.721016] Starting dynamic subtest: smem0
[112.353972] Dynamic subtest smem0: SUCCESS (33.624s)
[112.355200] Subtest hog-create: SUCCESS (33.625s)
[112.539039] [010/155] (907s left) syncobj_timeline (etime-single-wait-all-submitted)
[112.599079] Starting subtest: etime-single-wait-all-submitted
[112.703811] Subtest etime-single-wait-all-submitted: SUCCESS (0.104s)
[112.793205] [011/155] (907s left) kms_joiner (basic-big-joiner)
[112.883576] Starting subtest: basic-big-joiner
[112.885273] Subtest basic-big-joiner: SKIP (0.000s)
[112.948098] [012/155] (907s left) gem_pxp (protected-encrypted-src-copy-not-readible)
[113.072067] Starting subtest: protected-encrypted-src-copy-not-readible
[113.205858] Subtest protected-encrypted-src-copy-not-readible: SUCCESS (0.125s)
[113.316474] [013/155] (906s left) kms_rotation_crc (exhaust-fences)
[113.403744] Starting subtest: exhaust-fences
[113.405565] Subtest exhaust-fences: SKIP (0.000s)
[113.469987] [014/155] (906s left) kms_chamelium_frames (hdmi-crc-multiple)
[113.854074] Subtest hdmi-crc-multiple: SKIP (0.000s)
[114.705826] [015/155] (905s left) kms_frontbuffer_tracking (psrhdr-rgb565-draw-pwrite)
[114.793958] Starting subtest: psrhdr-rgb565-draw-pwrite
[114.795731] Subtest psrhdr-rgb565-draw-pwrite: SKIP (0.000s)
[114.877129] [016/155] (905s left) gem_mmap_wc (bad-size)
[114.926481] Subtest bad-size: SKIP (0.000s)
[114.985167] [017/155] (905s left) kms_pm_dc (dc9-dpms)
[116.073841] Starting subtest: dc9-dpms
[117.514253] Subtest dc9-dpms: SUCCESS (1.440s)
[117.580836] [018/155] (902s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted-signa
[117.626499] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted-signaled
[117.627354] Subtest multi-wait-for-submit-available-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[117.718957] [019/155] (902s left) kms_cursor_edge_walk (128x128-top-bottom)
[117.811184] Starting subtest: 128x128-top-bottom
[117.812211] Starting dynamic subtest: pipe-A-eDP-1
[121.327949] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.516s)
[121.338343] Starting dynamic subtest: pipe-D-eDP-1
[124.446473] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.118s)
[124.447428] Subtest 128x128-top-bottom: SUCCESS (6.636s)
[125.261322] [020/155] (895s left) gem_exec_whisper (basic-fds-forked)
[125.317366] Starting subtest: basic-fds-forked
[148.296654] Subtest basic-fds-forked: SUCCESS (22.980s)
[148.490408] [021/155] (871s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-render)
[148.599776] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-render
[148.601452] Subtest hdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[148.700849] [022/155] (871s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[148.803906] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu
[148.805687] Subtest psrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[148.899673] [023/155] (871s left) kms_panel_fitting (legacy)
[148.998591] Starting subtest: legacy
[148.999534] Starting dynamic subtest: pipe-A-eDP-1
[149.533637] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.536s)
[149.561466] Starting dynamic subtest: pipe-B-eDP-1
[151.129217] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.576s)
[151.158588] Starting dynamic subtest: pipe-C-eDP-1
[152.650102] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.500s)
[152.678472] Starting dynamic subtest: pipe-D-eDP-1
[154.161172] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.490s)
[154.162250] Subtest legacy: SUCCESS (5.164s)
[155.405587] [024/155] (865s left) kms_ccs (bad-pixel-format-y-tiled-gen12-mc-ccs)
[155.496914] Starting subtest: bad-pixel-format-y-tiled-gen12-mc-ccs
[155.497918] Starting dynamic subtest: pipe-A-eDP-1
[155.507298] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[155.515450] Starting dynamic subtest: pipe-B-eDP-1
[155.523840] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[155.531312] Starting dynamic subtest: pipe-C-eDP-1
[155.539191] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[155.546430] Starting dynamic subtest: pipe-D-eDP-1
[155.554532] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[155.556163] Subtest bad-pixel-format-y-tiled-gen12-mc-ccs: SKIP (0.003s)
[157.788552] [025/155] (862s left) kms_chamelium_hpd (dp-hpd-storm)
[158.174379] Subtest dp-hpd-storm: SKIP (0.000s)
[158.980490] [026/155] (861s left) sysfs_timeslice_duration (duration)
[159.043937] Starting subtest: duration
[159.045065] Starting dynamic subtest: vecs0
[172.280468] Dynamic subtest vecs0: SUCCESS (13.234s)
[172.292478] Starting dynamic subtest: ccs0
[185.432090] Dynamic subtest ccs0: SUCCESS (13.138s)
[185.441375] Starting dynamic subtest: vcs0
[200.258061] Dynamic subtest vcs0: SUCCESS (14.827s)
[200.265781] Starting dynamic subtest: bcs0
[213.408915] Dynamic subtest bcs0: SUCCESS (13.158s)
[213.419726] Starting dynamic subtest: rcs0
[226.555691] Dynamic subtest rcs0: SUCCESS (13.145s)
[226.566398] Starting dynamic subtest: vcs1
[241.282211] Dynamic subtest vcs1: SUCCESS (14.716s)
[241.283511] Subtest duration: SUCCESS (82.228s)
[241.347467] [027/155] (779s left) gem_exec_reloc (basic-write-wc-noreloc)
[241.398252] Subtest basic-write-wc-noreloc: SKIP (0.000s)
[241.466235] [028/155] (779s left) gem_exec_reloc (basic-softpin)
[241.518245] Subtest basic-softpin: SKIP (0.000s)
[241.592529] [029/155] (779s left) kms_cursor_legacy (short-flip-before-cursor-toggle)
[241.716029] Starting subtest: short-flip-before-cursor-toggle
[242.634078] Subtest short-flip-before-cursor-toggle: SUCCESS (0.918s)
[243.432895] [030/155] (777s left) kms_psr (psr-cursor-plane-onoff)
[243.532959] Starting subtest: psr-cursor-plane-onoff
[243.534013] Starting dynamic subtest: eDP-1
[245.250756] Dynamic subtest eDP-1: SUCCESS (1.718s)
[245.251719] Subtest psr-cursor-plane-onoff: SUCCESS (1.719s)
[246.100290] [031/155] (774s left) kms_ccs (missing-ccs-buffer-y-tiled-gen12-rc-ccs)
[246.182883] Starting subtest: missing-ccs-buffer-y-tiled-gen12-rc-ccs
[246.183889] Starting dynamic subtest: pipe-A-eDP-1
[246.192832] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[246.201213] Starting dynamic subtest: pipe-B-eDP-1
[246.210042] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[246.218147] Starting dynamic subtest: pipe-C-eDP-1
[246.226928] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[246.234911] Starting dynamic subtest: pipe-D-eDP-1
[246.242781] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[246.244344] Subtest missing-ccs-buffer-y-tiled-gen12-rc-ccs: SKIP (0.003s)
[248.463620] [032/155] (772s left) gem_exec_async (concurrent-writes)
[248.520488] Starting subtest: concurrent-writes
[248.521424] Starting dynamic subtest: rcs0
[248.533909] Dynamic subtest rcs0: SUCCESS (0.015s)
[248.544365] Starting dynamic subtest: bcs0
[248.545177] Dynamic subtest bcs0: SUCCESS (0.009s)
[248.553466] Starting dynamic subtest: vcs0
[248.554311] Dynamic subtest vcs0: SUCCESS (0.006s)
[248.561703] Starting dynamic subtest: vcs1
[248.562697] Dynamic subtest vcs1: SUCCESS (0.007s)
[248.570650] Starting dynamic subtest: vecs0
[248.571497] Dynamic subtest vecs0: SUCCESS (0.007s)
[248.578784] Starting dynamic subtest: ccs0
[248.579669] Dynamic subtest ccs0: SUCCESS (0.005s)
[248.580535] Subtest concurrent-writes: SUCCESS (0.051s)
[248.800360] [033/155] (771s left) perf_pmu (init-wait)
[248.838315] Starting subtest: init-wait
[248.839226] Starting dynamic subtest: rcs0
[248.841708] Dynamic subtest rcs0: SUCCESS (0.002s)
[248.849994] Starting dynamic subtest: bcs0
[248.850916] Dynamic subtest bcs0: SUCCESS (0.000s)
[248.858533] Starting dynamic subtest: vcs0
[248.859415] Dynamic subtest vcs0: SUCCESS (0.001s)
[248.866824] Starting dynamic subtest: vcs1
[248.867674] Dynamic subtest vcs1: SUCCESS (0.001s)
[248.875038] Starting dynamic subtest: vecs0
[248.875929] Dynamic subtest vecs0: SUCCESS (0.001s)
[248.883441] Starting dynamic subtest: ccs0
[248.884486] Dynamic subtest ccs0: SUCCESS (0.000s)
[248.885394] Subtest init-wait: SUCCESS (0.007s)
[248.946368] [034/155] (771s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-plflip-blt)
[249.029490] Starting subtest: fbcpsr-2p-scndscrn-indfb-plflip-blt
[249.031360] Subtest fbcpsr-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[249.136845] [035/155] (771s left) gem_fenced_exec_thrash (2-spare-fences)
[249.189279] Subtest 2-spare-fences: SKIP (0.000s)
[249.258536] [036/155] (771s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions-varying-size)
[249.372670] Starting subtest: short-flip-before-cursor-atomic-transitions-varying-size
[250.323121] Subtest short-flip-before-cursor-atomic-transitions-varying-size: SUCCESS (0.949s)
[251.189539] [037/155] (769s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[251.284415] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu
[251.286181] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[251.366777] [038/155] (769s left) gem_ctx_persistence (saturated-hostile-nopreempt)
[251.425246] Starting subtest: saturated-hostile-nopreempt
[251.426133] Starting dynamic subtest: rcs0
[251.476601] Dynamic subtest rcs0: SKIP (0.050s)
[251.485373] Starting dynamic subtest: bcs0
[251.489869] Dynamic subtest bcs0: SKIP (0.013s)
[251.497102] Starting dynamic subtest: vcs0
[251.502057] Dynamic subtest vcs0: SKIP (0.012s)
[251.511065] Starting dynamic subtest: vcs1
[251.514839] Dynamic subtest vcs1: SKIP (0.013s)
[251.522189] Starting dynamic subtest: vecs0
[251.527920] Dynamic subtest vecs0: SKIP (0.013s)
[251.535561] Starting dynamic subtest: ccs0
[251.539802] Dynamic subtest ccs0: SKIP (0.012s)
[251.541598] Subtest saturated-hostile-nopreempt: SKIP (0.114s)
[251.625407] [039/155] (769s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-indfb-plflip-blt)
[251.712423] Starting subtest: fbchdr-2p-primscrn-indfb-plflip-blt
[251.714269] Subtest fbchdr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[251.799119] [040/155] (769s left) kms_vrr (flip-basic)
[251.894160] Starting subtest: flip-basic
[251.897032] Subtest flip-basic: SKIP (0.000s)
[251.973615] [041/155] (769s left) syncobj_timeline (device-submit-unordered)
[252.018206] Starting subtest: device-submit-unordered
[252.019679] Subtest device-submit-unordered: SUCCESS (0.001s)
[252.128603] [042/155] (768s left) kms_frontbuffer_tracking (fbchdr-rgb565-draw-mmap-wc)
[252.222178] Starting subtest: fbchdr-rgb565-draw-mmap-wc
[252.224046] Subtest fbchdr-rgb565-draw-mmap-wc: SKIP (0.000s)
[252.308417] [043/155] (768s left) api_intel_allocator (two-level-inception-interruptible)
[252.352809] Starting subtest: two-level-inception-interruptible
[257.804438] Subtest two-level-inception-interruptible: SUCCESS (5.406s)
[257.898198] [044/155] (763s left) kms_psr (fbc-psr2-sprite-plane-onoff)
[257.999490] Starting subtest: fbc-psr2-sprite-plane-onoff
[258.000423] Starting dynamic subtest: eDP-1
[258.002269] Dynamic subtest eDP-1: SKIP (0.000s)
[258.003941] Subtest fbc-psr2-sprite-plane-onoff: SKIP (0.001s)
[258.084176] [045/155] (763s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-downscaling)
[258.174179] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-downscaling
[258.175203] Starting dynamic subtest: pipe-A-default-mode
[259.346888] Dynamic subtest pipe-A-default-mode: SKIP (1.172s)
[259.348801] Subtest flip-64bpp-xtile-to-32bpp-xtile-downscaling: SKIP (1.173s)
[259.515190] [046/155] (761s left) syncobj_wait (reset-during-wait-for-submit)
[259.575674] Starting subtest: reset-during-wait-for-submit
[259.677971] Subtest reset-during-wait-for-submit: SUCCESS (0.102s)
[259.770312] [047/155] (761s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-pwrite)
[259.862346] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-pwrite
[266.807195] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (6.946s)
[266.938293] [048/155] (754s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-gtt)
[267.042481] Starting subtest: fbc-rgb565-draw-mmap-gtt
[267.044443] Subtest fbc-rgb565-draw-mmap-gtt: SKIP (0.000s)
[267.139846] [049/155] (754s left) gem_exec_whisper (basic-sync)
[267.198564] Starting subtest: basic-sync
[273.780865] Subtest basic-sync: SUCCESS (6.572s)
[273.953023] [050/155] (747s left) gem_spin_batch (resubmit-new-all)
[274.004827] Starting subtest: resubmit-new-all
[274.005855] Starting dynamic subtest: rcs0
[274.024186] Dynamic subtest rcs0: SUCCESS (0.019s)
[274.033346] Starting dynamic subtest: bcs0
[274.038638] Dynamic subtest bcs0: SUCCESS (0.014s)
[274.046385] Starting dynamic subtest: vcs0
[274.055831] Dynamic subtest vcs0: SUCCESS (0.016s)
[274.064865] Starting dynamic subtest: vcs1
[274.069273] Dynamic subtest vcs1: SUCCESS (0.013s)
[274.079095] Starting dynamic subtest: vecs0
[274.087920] Dynamic subtest vecs0: SUCCESS (0.018s)
[274.096095] Starting dynamic subtest: ccs0
[274.106150] Dynamic subtest ccs0: SUCCESS (0.017s)
[274.107112] Subtest resubmit-new-all: SUCCESS (0.102s)
[275.394368] [051/155] (745s left) kms_psr (fbc-pr-primary-mmap-cpu)
[275.489479] Starting subtest: fbc-pr-primary-mmap-cpu
[275.491420] Subtest fbc-pr-primary-mmap-cpu: SKIP (0.000s)
[275.568664] [052/155] (745s left) gem_exec_flush (basic-uc-prw-default)
[275.627383] Starting subtest: basic-uc-prw-default
[281.098893] Subtest basic-uc-prw-default: SUCCESS (5.471s)
[281.271542] [053/155] (740s left) kms_cursor_legacy (flip-vs-cursor-legacy)
[281.387716] Starting subtest: flip-vs-cursor-legacy
[289.049978] Subtest flip-vs-cursor-legacy: SUCCESS (7.660s)
[289.884373] [054/155] (731s left) kms_atomic (crtc-invalid-params)
[289.976396] Starting subtest: crtc-invalid-params
[289.977251] Starting dynamic subtest: pipe-A-eDP-1
[290.339398] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.361s)
[290.340438] Subtest crtc-invalid-params: SUCCESS (0.363s)
[291.216036] [055/155] (730s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-cur-indfb-move)
[291.314785] Starting subtest: fbcpsrhdr-1p-primscrn-cur-indfb-move
[291.316464] Subtest fbcpsrhdr-1p-primscrn-cur-indfb-move: SKIP (0.000s)
[291.409840] [056/155] (730s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[291.516967] Starting subtest: fbcpsrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[291.518950] Subtest fbcpsrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[291.623059] [057/155] (729s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[291.718303] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc
[291.720261] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[291.806790] [058/155] (729s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[291.896464] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-gtt
[291.898291] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[291.975139] [059/155] (729s left) kms_frontbuffer_tracking (hdr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[292.062724] Starting subtest: hdr-1p-primscrn-pri-indfb-draw-mmap-gtt
[292.064419] Subtest hdr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[292.168331] [060/155] (729s left) kms_rotation_crc (primary-4-tiled-reflect-x-0)
[292.255043] Starting subtest: primary-4-tiled-reflect-x-0
[299.217052] Subtest primary-4-tiled-reflect-x-0: SUCCESS (6.962s)
[300.394502] [061/155] (721s left) i915_drm_fdinfo (virtual-busy)
[300.429244] Starting subtest: virtual-busy
[300.431267] Subtest virtual-busy: SKIP (0.000s)
[300.503446] [062/155] (721s left) kms_psr2_sf (psr2-cursor-plane-update-sf)
[300.592727] Starting subtest: psr2-cursor-plane-update-sf
[300.593699] Starting dynamic subtest: pipe-A-eDP-1
[301.171928] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.578s)
[301.183166] Starting dynamic subtest: pipe-B-eDP-1
[302.481640] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.308s)
[302.482508] Subtest psr2-cursor-plane-update-sf: SUCCESS (1.890s)
[303.340902] [063/155] (718s left) gem_mmap (basic)
[303.392974] Subtest basic: SKIP (0.000s)
[303.446239] [064/155] (718s left) syncobj_timeline (invalid-wait-illegal-handle)
[303.489420] Starting subtest: invalid-wait-illegal-handle
[303.490414] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[303.582745] [065/155] (718s left) kms_async_flips (async-flip-hang)
[303.668771] Starting subtest: async-flip-hang
[304.043273] Starting dynamic subtest: pipe-A-eDP-1
[319.801889] Dynamic subtest pipe-A-eDP-1: SUCCESS (15.757s)
[320.184983] Starting dynamic subtest: pipe-B-eDP-1
[335.863470] Dynamic subtest pipe-B-eDP-1: SUCCESS (15.682s)
[336.245196] Starting dynamic subtest: pipe-C-eDP-1
[351.859042] Dynamic subtest pipe-C-eDP-1: SUCCESS (15.618s)
[352.231739] Starting dynamic subtest: pipe-D-eDP-1
[367.801489] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.574s)
[367.802904] Subtest async-flip-hang: SUCCESS (64.130s)
[368.999136] [066/155] (652s left) vgem_basic (dmabuf-fence-before)
[369.034578] Starting subtest: dmabuf-fence-before
[369.035325] Subtest dmabuf-fence-before: SUCCESS (0.000s)
[369.087709] [067/155] (652s left) kms_psr (psr2-cursor-plane-onoff)
[369.174709] Starting subtest: psr2-cursor-plane-onoff
[369.175611] Starting dynamic subtest: eDP-1
[370.864436] Dynamic subtest eDP-1: SUCCESS (1.690s)
[370.865369] Subtest psr2-cursor-plane-onoff: SUCCESS (1.690s)
[371.748707] [068/155] (650s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[371.842955] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-gtt
[371.844969] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[371.933061] [069/155] (650s left) kms_content_protection (lic-type-0)
[372.069650] Starting subtest: lic-type-0
[372.071531] Subtest lic-type-0: SKIP (0.000s)
[372.211796] [070/155] (649s left) gem_exec_params (no-bsd)
[372.275260] Starting subtest: no-bsd
[372.276194] Subtest no-bsd: SUCCESS (0.002s)
[374.340138] [071/155] (647s left) kms_ccs (crc-primary-rotation-180-yf-tiled-ccs)
[374.422389] Starting subtest: crc-primary-rotation-180-yf-tiled-ccs
[374.423178] Starting dynamic subtest: pipe-A-eDP-1
[374.431265] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[374.438877] Starting dynamic subtest: pipe-B-eDP-1
[374.455285] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[374.463194] Starting dynamic subtest: pipe-C-eDP-1
[374.471393] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[374.479058] Starting dynamic subtest: pipe-D-eDP-1
[374.487324] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[374.488957] Subtest crc-primary-rotation-180-yf-tiled-ccs: SKIP (0.006s)
[376.713476] [072/155] (645s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-indfb-draw-blt)
[376.800188] Starting subtest: psrhdr-1p-offscreen-pri-indfb-draw-blt
[376.801989] Subtest psrhdr-1p-offscreen-pri-indfb-draw-blt: SKIP (0.000s)
[376.886281] [073/155] (645s left) gem_exec_schedule (thriceslice)
[376.948289] Starting subtest: thriceslice
[376.949407] Starting dynamic subtest: rcs0
[376.978818] Dynamic subtest rcs0: SUCCESS (0.030s)
[376.992852] Starting dynamic subtest: bcs0
[377.007515] Dynamic subtest bcs0: SUCCESS (0.028s)
[377.017468] Starting dynamic subtest: vcs0
[377.059295] Dynamic subtest vcs0: SUCCESS (0.050s)
[377.071864] Starting dynamic subtest: vcs1
[377.090616] Dynamic subtest vcs1: SUCCESS (0.029s)
[377.098735] Starting dynamic subtest: vecs0
[377.119444] Dynamic subtest vecs0: SUCCESS (0.028s)
[377.127405] Starting dynamic subtest: ccs0
[377.151901] Dynamic subtest ccs0: SUCCESS (0.032s)
[377.153468] Subtest thriceslice: SUCCESS (0.204s)
[378.381360] [074/155] (643s left) kms_flip (nonexisting-fb)
[378.468727] Starting subtest: nonexisting-fb
[378.471541] Starting dynamic subtest: A-eDP1
[379.624818] Dynamic subtest A-eDP1: SUCCESS (1.154s)
[379.635163] Starting dynamic subtest: B-eDP1
[380.753398] Dynamic subtest B-eDP1: SUCCESS (1.127s)
[380.762483] Starting dynamic subtest: C-eDP1
[381.886988] Dynamic subtest C-eDP1: SUCCESS (1.133s)
[381.896513] Starting dynamic subtest: D-eDP1
[383.022188] Dynamic subtest D-eDP1: SUCCESS (1.134s)
[383.024728] Subtest nonexisting-fb: SUCCESS (4.556s)
[384.198777] [075/155] (638s left) gen9_exec_parse (shadow-peek)
[384.252828] Subtest shadow-peek: SKIP (0.000s)
[384.323647] [076/155] (637s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[384.417950] Starting subtest: hdr-2p-primscrn-pri-indfb-draw-mmap-gtt
[384.419875] Subtest hdr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[384.519556] [077/155] (637s left) kms_dsc (dsc-basic)
[384.604922] Starting subtest: dsc-basic
[384.609830] Subtest dsc-basic: SKIP (0.000s)
[384.688430] [078/155] (637s left) gem_ctx_freq (sysfs)
[384.737451] Starting subtest: sysfs
[384.738526] Starting dynamic subtest: gt0
[390.213166] Dynamic subtest gt0: SUCCESS (5.475s)
[390.222160] Starting dynamic subtest: gt1
[431.331230] Dynamic subtest gt1: SUCCESS (41.106s)
[431.332396] Subtest sysfs: SUCCESS (46.584s)
[431.427274] [079/155] (590s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb101010-draw-mmap-cpu)
[431.519814] Starting subtest: fbcpsrhdr-rgb101010-draw-mmap-cpu
[431.521500] Subtest fbcpsrhdr-rgb101010-draw-mmap-cpu: SKIP (0.000s)
[431.598760] [080/155] (590s left) gem_exec_schedule (smoketest-all)
[431.654898] Starting subtest: smoketest-all
[464.002871] Subtest smoketest-all: SUCCESS (32.320s)
[465.360903] [081/155] (557s left) kms_universal_plane (universal-plane-sanity)
[465.467354] Starting subtest: universal-plane-sanity
[465.468248] Starting dynamic subtest: pipe-A-eDP-1
[466.057877] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.590s)
[466.066590] Starting dynamic subtest: pipe-B-eDP-1
[467.305051] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.246s)
[467.313575] Starting dynamic subtest: pipe-C-eDP-1
[468.515249] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.207s)
[468.527930] Starting dynamic subtest: pipe-D-eDP-1
[469.754354] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.238s)
[469.755353] Subtest universal-plane-sanity: SUCCESS (4.287s)
[470.634535] [082/155] (551s left) kms_chamelium_audio (dp-audio-edid)
[471.022636] Subtest dp-audio-edid: SKIP (0.000s)
[471.842243] [083/155] (550s left) kms_big_fb (linear-32bpp-rotate-0)
[471.947400] Starting subtest: linear-32bpp-rotate-0
[473.559229] Subtest linear-32bpp-rotate-0: SUCCESS (1.612s)
[473.676214] [084/155] (548s left) kms_flip (plain-flip-fb-recreate-interruptible)
[473.759578] Starting subtest: plain-flip-fb-recreate-interruptible
[473.761971] Starting dynamic subtest: A-eDP1
[482.662112] Dynamic subtest A-eDP1: SUCCESS (8.900s)
[482.672110] Starting dynamic subtest: B-eDP1
[491.489923] Dynamic subtest B-eDP1: SUCCESS (8.826s)
[491.503152] Starting dynamic subtest: C-eDP1
[500.308094] Dynamic subtest C-eDP1: SUCCESS (8.817s)
[500.322314] Starting dynamic subtest: D-eDP1
[509.134845] Dynamic subtest D-eDP1: SUCCESS (8.823s)
[509.137284] Subtest plain-flip-fb-recreate-interruptible: SUCCESS (35.377s)
[510.315311] [085/155] (512s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180)
[510.409698] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180
[510.411698] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s)
[510.505880] [086/155] (512s left) kms_rotation_crc (sprite-rotation-90-pos-100-0)
[510.600511] Starting subtest: sprite-rotation-90-pos-100-0
[510.602249] Subtest sprite-rotation-90-pos-100-0: SKIP (0.001s)
[510.672025] [087/155] (511s left) kms_psr (fbc-psr-sprite-mmap-gtt)
[510.751485] Starting subtest: fbc-psr-sprite-mmap-gtt
[510.752394] Starting dynamic subtest: eDP-1
[510.754121] Dynamic subtest eDP-1: SKIP (0.000s)
[510.755894] Subtest fbc-psr-sprite-mmap-gtt: SKIP (0.001s)
[510.822376] [088/155] (511s left) gem_mmap (bad-size)
[510.866877] Subtest bad-size: SKIP (0.000s)
[510.929957] [089/155] (511s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-onoff)
[511.016796] Starting subtest: psrhdr-2p-primscrn-spr-indfb-onoff
[511.018760] Subtest psrhdr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[511.118452] [090/155] (511s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-move)
[511.205414] Starting subtest: fbc-2p-primscrn-cur-indfb-move
[511.206956] Subtest fbc-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[511.300901] [091/155] (511s left) kms_cursor_crc (cursor-offscreen-max-size)
[511.587837] Starting subtest: cursor-offscreen-max-size
[511.590870] Subtest cursor-offscreen-max-size: SKIP (0.000s)
[511.712494] [092/155] (511s left) sw_sync (alloc_timeline)
[511.747615] Starting subtest: alloc_timeline
[511.748503] Subtest alloc_timeline: SUCCESS (0.000s)
[511.799842] [093/155] (511s left) kms_flip (flip-vs-dpms-off-vs-modeset)
[511.888431] Starting subtest: flip-vs-dpms-off-vs-modeset
[511.890467] Starting dynamic subtest: A-eDP1
[515.548570] Dynamic subtest A-eDP1: SUCCESS (3.657s)
[515.557226] Starting dynamic subtest: B-eDP1
[518.769386] Dynamic subtest B-eDP1: SUCCESS (3.219s)
[518.778503] Starting dynamic subtest: C-eDP1
[521.961260] Dynamic subtest C-eDP1: SUCCESS (3.191s)
[521.970312] Starting dynamic subtest: D-eDP1
[525.183511] Dynamic subtest D-eDP1: SUCCESS (3.222s)
[525.186375] Subtest flip-vs-dpms-off-vs-modeset: SUCCESS (13.298s)
[525.989296] [094/155] (496s left) syncobj_timeline (single-wait-for-submit-available-submitted)
[526.041706] Starting subtest: single-wait-for-submit-available-submitted
[526.042625] Subtest single-wait-for-submit-available-submitted: SUCCESS (0.000s)
[526.132749] [095/155] (496s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-wc)
[526.222588] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-wc
[530.563550] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (4.343s)
[531.700926] [096/155] (491s left) gem_softpin (softpin)
[531.756093] Starting subtest: softpin
[564.055851] Subtest softpin: SUCCESS (32.287s)
[564.164815] [097/155] (458s left) kms_plane (pixel-format-4-tiled-modifier-source-clamping)
[564.250328] Starting subtest: pixel-format-4-tiled-modifier-source-clamping
[564.278071] Starting dynamic subtest: pipe-A-plane-0
[564.677924] Dynamic subtest pipe-A-plane-0: SUCCESS (0.400s)
[564.686749] Starting dynamic subtest: pipe-A-plane-3
[565.000129] Dynamic subtest pipe-A-plane-3: SUCCESS (0.321s)
[566.086413] Starting dynamic subtest: pipe-B-plane-0
[566.477972] Dynamic subtest pipe-B-plane-0: SUCCESS (0.400s)
[566.488189] Starting dynamic subtest: pipe-B-plane-3
[566.822733] Dynamic subtest pipe-B-plane-3: SUCCESS (0.343s)
[567.133631] Subtest pixel-format-4-tiled-modifier-source-clamping: SUCCESS (2.881s)
[567.973694] [098/155] (455s left) kms_flip_scaled_crc (flip-32bpp-xtile-to-64bpp-xtile-upscaling)
[568.072441] Starting subtest: flip-32bpp-xtile-to-64bpp-xtile-upscaling
[568.073533] Starting dynamic subtest: pipe-A-default-mode
[570.808550] Dynamic subtest pipe-A-default-mode: SUCCESS (2.735s)
[570.809508] Subtest flip-32bpp-xtile-to-64bpp-xtile-upscaling: SUCCESS (2.736s)
[571.669211] [099/155] (451s left) kms_frontbuffer_tracking (fbc-tiling-4)
[571.767655] Starting subtest: fbc-tiling-4
[576.060929] Subtest fbc-tiling-4: SUCCESS (4.293s)
[577.268577] [100/155] (445s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[577.367028] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[578.159201] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (0.792s)
[578.326552] [101/155] (444s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-shrfb-plflip-blt)
[578.425165] Starting subtest: fbchdr-2p-scndscrn-shrfb-plflip-blt
[578.427019] Subtest fbchdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[578.530641] [102/155] (444s left) tools_test (sysfs_l3_parity)
[578.566308] Starting subtest: sysfs_l3_parity
[578.640916] Subtest sysfs_l3_parity: SKIP (0.076s)
[578.691239] [103/155] (444s left) i915_getparams_basic (basic-eu-total)
[578.747560] Starting subtest: basic-eu-total
[578.749649] Subtest basic-eu-total: SUCCESS (0.000s)
[578.855550] [104/155] (444s left) gem_mmap_offset (bad-flags)
[578.909474] Starting subtest: bad-flags
[578.910455] Subtest bad-flags: SUCCESS (0.000s)
[579.010794] [105/155] (444s left) kms_plane_scaling (planes-downscale-factor-0-75)
[579.094402] Starting subtest: planes-downscale-factor-0-75
[579.095319] Starting dynamic subtest: pipe-A
[579.114916] Dynamic subtest pipe-A: SKIP (0.019s)
[579.123949] Starting dynamic subtest: pipe-B
[579.147207] Dynamic subtest pipe-B: SKIP (0.032s)
[579.155410] Starting dynamic subtest: pipe-C
[579.179909] Dynamic subtest pipe-C: SKIP (0.032s)
[579.188201] Starting dynamic subtest: pipe-D
[579.212643] Dynamic subtest pipe-D: SKIP (0.032s)
[579.214391] Subtest planes-downscale-factor-0-75: SKIP (0.118s)
[579.315550] [106/155] (443s left) kms_cursor_crc (cursor-rapid-movement-512x170)
[579.574135] Starting subtest: cursor-rapid-movement-512x170
[579.575928] Subtest cursor-rapid-movement-512x170: SKIP (0.001s)
[579.705219] [107/155] (443s left) dmabuf (all-tests)
[579.734957] Starting subtest: all-tests
[579.738152] Subtest all-tests: SKIP (0.000s)
[579.794057] [108/155] (443s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite)
[579.882794] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite
[588.856604] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.974s)
[588.978730] [109/155] (434s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc)
[589.076605] Starting subtest: ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc
[589.077653] Starting dynamic subtest: pipe-A-eDP-1
[589.086173] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[589.095474] Starting dynamic subtest: pipe-B-eDP-1
[589.103665] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[589.112323] Starting dynamic subtest: pipe-C-eDP-1
[589.120390] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[589.128666] Starting dynamic subtest: pipe-D-eDP-1
[589.136762] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[589.138425] Subtest ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc: SKIP (0.005s)
[591.415209] [110/155] (432s left) kms_cursor_legacy (cursorb-vs-flipb-atomic-transitions-varying-size)
[591.544491] Starting subtest: cursorB-vs-flipB-atomic-transitions-varying-size
[591.546244] Subtest cursorB-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[591.685534] [111/155] (431s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-fully-sf)
[591.770517] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-fully-sf
[591.772273] Subtest fbc-pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[591.838475] [112/155] (431s left) gem_exec_nop (basic-parallel)
[591.885782] Starting subtest: basic-parallel
[608.356883] Subtest basic-parallel: SUCCESS (16.461s)
[608.562976] [113/155] (414s left) kms_frontbuffer_tracking (fbcpsr-farfromfence-mmap-gtt)
[608.670443] Starting subtest: fbcpsr-farfromfence-mmap-gtt
[608.672140] Subtest fbcpsr-farfromfence-mmap-gtt: SKIP (0.000s)
[608.758939] [114/155] (414s left) kms_big_fb (x-tiled-64bpp-rotate-270)
[608.849062] Starting subtest: x-tiled-64bpp-rotate-270
[608.868392] Subtest x-tiled-64bpp-rotate-270: SKIP (0.019s)
[608.980688] [115/155] (414s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-indfb-draw-mmap-wc)
[609.067258] Starting subtest: fbchdr-1p-offscreen-pri-indfb-draw-mmap-wc
[609.068917] Subtest fbchdr-1p-offscreen-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[609.182838] [116/155] (414s left) i915_pm_rpm (gem-execbuf-stress)
[611.404190] Starting subtest: gem-execbuf-stress
[611.405222] Starting dynamic subtest: smem0
[624.325335] Dynamic subtest smem0: SUCCESS (12.921s)
[624.338456] Starting dynamic subtest: extra-wait-smem0
[686.109999] Dynamic subtest extra-wait-smem0: SUCCESS (61.769s)
[686.111148] Subtest gem-execbuf-stress: SUCCESS (74.696s)
[686.503780] [117/155] (337s left) kms_cursor_legacy (cursorb-vs-flipb-legacy)
[686.627475] Starting subtest: cursorB-vs-flipB-legacy
[686.629331] Subtest cursorB-vs-flipB-legacy: SKIP (0.001s)
[686.765406] [118/155] (336s left) gem_bad_reloc (negative-reloc)
[686.823354] Subtest negative-reloc: SKIP (0.000s)
[686.886960] [119/155] (336s left) gem_userptr_blits (huge-split)
[686.949160] Starting subtest: huge-split
[686.955574] Subtest huge-split: SUCCESS (0.006s)
[687.067076] [120/155] (336s left) gem_exec_reloc (basic-write-wc-active)
[687.118180] Subtest basic-write-wc-active: SKIP (0.000s)
[687.183761] [121/155] (336s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-draw-blt)
[687.267904] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-draw-blt
[687.269641] Subtest psrhdr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[687.365262] [122/155] (336s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render)
[687.452394] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render
[687.454178] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[687.545357] [123/155] (336s left) kms_vrr (seamless-rr-switch-vrr)
[687.631054] Starting subtest: seamless-rr-switch-vrr
[687.633829] Subtest seamless-rr-switch-vrr: SKIP (0.000s)
[687.699988] [124/155] (336s left) syncobj_wait (invalid-multi-wait-unsubmitted)
[687.742638] Starting subtest: invalid-multi-wait-unsubmitted
[687.743517] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[687.838368] [125/155] (336s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-spr-indfb-draw-mmap-wc)
[687.921931] Starting subtest: psrhdr-1p-primscrn-spr-indfb-draw-mmap-wc
[687.923857] Subtest psrhdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[688.012030] [126/155] (335s left) prime_busy (before)
[688.066122] Starting subtest: before
[688.069511] Starting dynamic subtest: rcs0
[688.092720] Dynamic subtest rcs0: SUCCESS (0.023s)
[688.102655] Starting dynamic subtest: bcs0
[688.131197] Dynamic subtest bcs0: SUCCESS (0.038s)
[688.139300] Starting dynamic subtest: vcs0
[688.171108] Dynamic subtest vcs0: SUCCESS (0.039s)
[688.179064] Starting dynamic subtest: vcs1
[688.209856] Dynamic subtest vcs1: SUCCESS (0.038s)
[688.217794] Starting dynamic subtest: vecs0
[688.248700] Dynamic subtest vecs0: SUCCESS (0.038s)
[688.257102] Starting dynamic subtest: ccs0
[688.291054] Dynamic subtest ccs0: SUCCESS (0.042s)
[688.399055] Subtest before: SUCCESS (0.332s)
[688.471233] [127/155] (335s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-mmap-wc)
[688.565196] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-mmap-wc
[688.567059] Subtest hdr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[688.661805] [128/155] (335s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[688.759346] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[688.761109] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[688.852448] [129/155] (335s left) intel_hwmon (hwmon-read)
[688.906355] Subtest hwmon-read: SKIP (0.000s)
[688.965123] [130/155] (335s left) syncobj_wait (wait-any-complex)
[689.012342] Starting subtest: wait-any-complex
[689.075807] Subtest wait-any-complex: SUCCESS (0.062s)
[689.148540] [131/155] (335s left) gem_render_copy (y-tiled-ccs-to-yf-tiled)
[689.200415] Starting subtest: y-tiled-ccs-to-yf-tiled
[689.202368] Subtest y-tiled-ccs-to-yf-tiled: SKIP (0.000s)
[689.378542] [132/155] (334s left) gem_ppgtt (flink-and-close-vma-leak)
[689.431169] Starting subtest: flink-and-close-vma-leak
[689.472823] Subtest flink-and-close-vma-leak: SUCCESS (0.041s)
[689.584811] [133/155] (334s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc)
[689.673930] Starting subtest: psr-rgb565-draw-mmap-wc
[696.566763] Subtest psr-rgb565-draw-mmap-wc: SUCCESS (6.894s)
[696.667029] [134/155] (327s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[696.753976] Starting subtest: fbchdr-1p-primscrn-pri-shrfb-draw-mmap-wc
[696.756087] Subtest fbchdr-1p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[696.847333] [135/155] (327s left) kms_cursor_legacy (single-move)
[696.955974] Starting subtest: single-move
[696.956835] Starting dynamic subtest: pipe-A
[702.381783] Dynamic subtest pipe-A: SUCCESS (5.379s)
[702.394681] Starting dynamic subtest: pipe-B
[707.765504] Dynamic subtest pipe-B: SUCCESS (5.381s)
[707.779095] Starting dynamic subtest: pipe-C
[713.147935] Dynamic subtest pipe-C: SUCCESS (5.380s)
[713.158494] Starting dynamic subtest: pipe-D
[718.530659] Dynamic subtest pipe-D: SUCCESS (5.380s)
[718.540907] Starting dynamic subtest: all-pipes
[723.932907] Dynamic subtest all-pipes: SUCCESS (5.386s)
[723.933908] Subtest single-move: SUCCESS (26.918s)
[724.016771] [136/155] (300s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-indfb-draw-blt)
[724.106386] Starting subtest: fbchdr-2p-scndscrn-pri-indfb-draw-blt
[724.108593] Subtest fbchdr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[724.208160] [137/155] (300s left) syncobj_basic (bad-create-flags)
[724.261072] Starting subtest: bad-create-flags
[724.262084] Subtest bad-create-flags: SUCCESS (0.000s)
[724.378376] [138/155] (300s left) gem_exec_balancer (bonded-true-hang)
[724.670729] Starting subtest: bonded-true-hang
[724.672960] Subtest bonded-true-hang: SKIP (0.000s)
[724.753518] [139/155] (299s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-20x20)
[724.841450] Starting subtest: planes-downscale-factor-0-75-upscale-20x20
[724.842422] Starting dynamic subtest: pipe-A
[724.906168] Dynamic subtest pipe-A: SUCCESS (0.065s)
[724.916523] Starting dynamic subtest: pipe-B
[726.025308] Dynamic subtest pipe-B: SUCCESS (1.118s)
[726.034348] Starting dynamic subtest: pipe-C
[727.142765] Dynamic subtest pipe-C: SUCCESS (1.117s)
[727.151546] Starting dynamic subtest: pipe-D
[728.270713] Dynamic subtest pipe-D: SUCCESS (1.127s)
[728.271521] Subtest planes-downscale-factor-0-75-upscale-20x20: SUCCESS (3.431s)
[729.425875] [140/155] (295s left) kms_big_fb (y-tiled-16bpp-rotate-270)
[729.524915] Starting subtest: y-tiled-16bpp-rotate-270
[729.526940] Subtest y-tiled-16bpp-rotate-270: SKIP (0.000s)
[729.618787] [141/155] (294s left) gem_fenced_exec_thrash (no-spare-fences-busy)
[729.668471] Subtest no-spare-fences-busy: SKIP (0.000s)
[729.733923] [142/155] (294s left) kms_busy (extended-modeset-hang-oldfb)
[729.818226] Starting subtest: extended-modeset-hang-oldfb
[729.819015] Starting dynamic subtest: pipe-A
[741.713972] Dynamic subtest pipe-A: SUCCESS (11.893s)
[741.724615] Starting dynamic subtest: pipe-D
[753.976735] Dynamic subtest pipe-D: SUCCESS (12.262s)
[753.977894] Subtest extended-modeset-hang-oldfb: SUCCESS (24.159s)
[754.865257] [143/155] (269s left) kms_pipe_crc_basic (nonblocking-crc)
[754.947456] Starting subtest: nonblocking-crc
[754.948514] Starting dynamic subtest: pipe-A-eDP-1
[755.699602] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.751s)
[755.708696] Starting dynamic subtest: pipe-B-eDP-1
[757.089106] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.388s)
[757.098342] Starting dynamic subtest: pipe-C-eDP-1
[758.474649] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.384s)
[758.485984] Starting dynamic subtest: pipe-D-eDP-1
[759.835536] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.360s)
[759.836554] Subtest nonblocking-crc: SUCCESS (4.890s)
[760.712632] [144/155] (263s left) gem_userptr_blits (unsync-unmap-cycles)
[760.778571] Subtest unsync-unmap-cycles: SKIP (0.000s)
[760.900314] [145/155] (263s left) kms_color (ctm-green-to-red)
[760.988908] Starting subtest: ctm-green-to-red
[760.989867] Starting dynamic subtest: pipe-A-eDP-1
[761.720461] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.732s)
[761.729053] Starting dynamic subtest: pipe-B-eDP-1
[763.110487] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.389s)
[763.119514] Starting dynamic subtest: pipe-C-eDP-1
[764.491384] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.380s)
[764.500211] Starting dynamic subtest: pipe-D-eDP-1
[765.857815] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.365s)
[765.858865] Subtest ctm-green-to-red: SUCCESS (4.870s)
[766.737292] [146/155] (257s left) kms_lease (lease-again)
[766.825483] Starting subtest: lease-again
[766.826509] Starting dynamic subtest: pipe-A-eDP-1
[766.827769] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.002s)
[766.837271] Starting dynamic subtest: pipe-B-eDP-1
[766.838202] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.002s)
[766.846856] Starting dynamic subtest: pipe-C-eDP-1
[766.847789] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.002s)
[766.856024] Starting dynamic subtest: pipe-D-eDP-1
[766.856896] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.002s)
[766.857722] Subtest lease-again: SUCCESS (0.013s)
[766.927172] [147/155] (257s left) gem_exec_fence (concurrent)
[767.097733] Starting subtest: concurrent
[767.099561] Subtest concurrent: SKIP (0.000s)
[768.401596] [148/155] (256s left) gem_exec_schedule (pi-shared-iova)
[768.578345] Starting subtest: pi-shared-iova
[768.579427] Starting dynamic subtest: rcs0
[768.648790] Dynamic subtest rcs0: SUCCESS (0.079s)
[768.662282] Starting dynamic subtest: vcs0
[768.711690] Dynamic subtest vcs0: SUCCESS (0.061s)
[768.722362] Starting dynamic subtest: vcs1
[768.774640] Dynamic subtest vcs1: SUCCESS (0.058s)
[768.787811] Starting dynamic subtest: vecs0
[768.827499] Dynamic subtest vecs0: SUCCESS (0.053s)
[768.840465] Starting dynamic subtest: ccs0
[768.887794] Dynamic subtest ccs0: SUCCESS (0.058s)
[768.889174] Subtest pi-shared-iova: SUCCESS (0.319s)
[770.418562] [149/155] (254s left) api_intel_bb (object-reloc-keep-cache)
[770.472396] Starting subtest: object-reloc-keep-cache
[770.477437] Subtest object-reloc-keep-cache: SKIP (0.004s)
[770.570413] [150/155] (254s left) kms_addfb_basic (size-max)
[770.659118] Starting subtest: size-max
[770.659986] Subtest size-max: SUCCESS (0.000s)
[770.759598] [151/155] (254s left) gem_exec_reloc (basic-gtt-cpu-active)
[770.814109] Subtest basic-gtt-cpu-active: SKIP (0.000s)
[770.873329] [152/155] (254s left) gem_exec_params (cliprects_ptr-dirt)
[770.929474] Starting subtest: cliprects_ptr-dirt
[770.930545] Subtest cliprects_ptr-dirt: SUCCESS (0.000s)
[771.030421] [153/155] (253s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-shrfb-draw-blt)
[771.122286] Starting subtest: psrhdr-2p-primscrn-pri-shrfb-draw-blt
[771.123925] Subtest psrhdr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[771.214448] [154/155] (253s left) kms_frontbuffer_tracking (hdr-rgb565-draw-mmap-wc)
[771.312905] Starting subtest: hdr-rgb565-draw-mmap-wc
[771.314661] Subtest hdr-rgb565-draw-mmap-wc: SKIP (0.000s)
[771.411445] [155/155] (253s left) gem_exec_flush (basic-batch-kernel-default-wb)
[771.464371] Starting subtest: basic-batch-kernel-default-wb
[778.298289] Subtest basic-batch-kernel-default-wb: SUCCESS (6.835s)
[778.494111] Closing watchdogs
results: parsing output: 0/ for test: kms_plane
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: gem_exec_fence
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: gem_exec_params
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: kms_big_fb
results: parsing output: 8/ for test: gem_create
results: parsing output: 9/ for test: syncobj_timeline
results: parsing output: 10/ for test: kms_joiner
results: parsing output: 11/ for test: gem_pxp
results: parsing output: 12/ for test: kms_rotation_crc
results: parsing output: 13/ for test: kms_chamelium_frames
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: gem_mmap_wc
results: parsing output: 16/ for test: kms_pm_dc
results: parsing output: 17/ for test: syncobj_timeline
results: parsing output: 18/ for test: kms_cursor_edge_walk
results: parsing output: 19/ for test: gem_exec_whisper
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: kms_panel_fitting
results: parsing output: 23/ for test: kms_ccs
results: parsing output: 24/ for test: kms_chamelium_hpd
results: parsing output: 25/ for test: sysfs_timeslice_duration
results: parsing output: 26/ for test: gem_exec_reloc
results: parsing output: 27/ for test: gem_exec_reloc
results: parsing output: 28/ for test: kms_cursor_legacy
results: parsing output: 29/ for test: kms_psr
results: parsing output: 30/ for test: kms_ccs
results: parsing output: 31/ for test: gem_exec_async
results: parsing output: 32/ for test: perf_pmu
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: gem_fenced_exec_thrash
results: parsing output: 35/ for test: kms_cursor_legacy
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: gem_ctx_persistence
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: kms_vrr
results: parsing output: 40/ for test: syncobj_timeline
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: api_intel_allocator
results: parsing output: 43/ for test: kms_psr
results: parsing output: 44/ for test: kms_flip_scaled_crc
results: parsing output: 45/ for test: syncobj_wait
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: gem_exec_whisper
results: parsing output: 49/ for test: gem_spin_batch
results: parsing output: 50/ for test: kms_psr
results: parsing output: 51/ for test: gem_exec_flush
results: parsing output: 52/ for test: kms_cursor_legacy
results: parsing output: 53/ for test: kms_atomic
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: kms_rotation_crc
results: parsing output: 60/ for test: i915_drm_fdinfo
results: parsing output: 61/ for test: kms_psr2_sf
results: parsing output: 62/ for test: gem_mmap
results: parsing output: 63/ for test: syncobj_timeline
results: parsing output: 64/ for test: kms_async_flips
results: parsing output: 65/ for test: vgem_basic
results: parsing output: 66/ for test: kms_psr
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: kms_content_protection
results: parsing output: 69/ for test: gem_exec_params
results: parsing output: 70/ for test: kms_ccs
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: gem_exec_schedule
results: parsing output: 73/ for test: kms_flip
results: parsing output: 74/ for test: gen9_exec_parse
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: kms_dsc
results: parsing output: 77/ for test: gem_ctx_freq
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: gem_exec_schedule
results: parsing output: 80/ for test: kms_universal_plane
results: parsing output: 81/ for test: kms_chamelium_audio
results: parsing output: 82/ for test: kms_big_fb
results: parsing output: 83/ for test: kms_flip
results: parsing output: 84/ for test: kms_big_fb
results: parsing output: 85/ for test: kms_rotation_crc
results: parsing output: 86/ for test: kms_psr
results: parsing output: 87/ for test: gem_mmap
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_cursor_crc
results: parsing output: 91/ for test: sw_sync
results: parsing output: 92/ for test: kms_flip
results: parsing output: 93/ for test: syncobj_timeline
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: gem_softpin
results: parsing output: 96/ for test: kms_plane
results: parsing output: 97/ for test: kms_flip_scaled_crc
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: kms_big_fb
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: tools_test
results: parsing output: 102/ for test: i915_getparams_basic
results: parsing output: 103/ for test: gem_mmap_offset
results: parsing output: 104/ for test: kms_plane_scaling
results: parsing output: 105/ for test: kms_cursor_crc
results: parsing output: 106/ for test: dmabuf
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: kms_ccs
results: parsing output: 109/ for test: kms_cursor_legacy
results: parsing output: 110/ for test: kms_psr2_sf
results: parsing output: 111/ for test: gem_exec_nop
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: kms_big_fb
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: i915_pm_rpm
results: parsing output: 116/ for test: kms_cursor_legacy
results: parsing output: 117/ for test: gem_bad_reloc
results: parsing output: 118/ for test: gem_userptr_blits
results: parsing output: 119/ for test: gem_exec_reloc
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: kms_vrr
results: parsing output: 123/ for test: syncobj_wait
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: prime_busy
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: intel_hwmon
results: parsing output: 129/ for test: syncobj_wait
results: parsing output: 130/ for test: gem_render_copy
results: parsing output: 131/ for test: gem_ppgtt
results: parsing output: 132/ for test: kms_frontbuffer_tracking
results: parsing output: 133/ for test: kms_frontbuffer_tracking
results: parsing output: 134/ for test: kms_cursor_legacy
results: parsing output: 135/ for test: kms_frontbuffer_tracking
results: parsing output: 136/ for test: syncobj_basic
results: parsing output: 137/ for test: gem_exec_balancer
results: parsing output: 138/ for test: kms_plane_scaling
results: parsing output: 139/ for test: kms_big_fb
results: parsing output: 140/ for test: gem_fenced_exec_thrash
results: parsing output: 141/ for test: kms_busy
results: parsing output: 142/ for test: kms_pipe_crc_basic
results: parsing output: 143/ for test: gem_userptr_blits
results: parsing output: 144/ for test: kms_color
results: parsing output: 145/ for test: kms_lease
results: parsing output: 146/ for test: gem_exec_fence
results: parsing output: 147/ for test: gem_exec_schedule
results: parsing output: 148/ for test: api_intel_bb
results: parsing output: 149/ for test: kms_addfb_basic
results: parsing output: 150/ for test: gem_exec_reloc
results: parsing output: 151/ for test: gem_exec_params
results: parsing output: 152/ for test: kms_frontbuffer_tracking
results: parsing output: 153/ for test: kms_frontbuffer_tracking
results: parsing output: 154/ for test: gem_exec_flush
Done.