[56.872622] Initializing watchdogs
[56.872762]   /dev/watchdog0
[56.887735] [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]
[56.892721] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.895537] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.899024] [001/155] (960s left) gem_mmap_gtt (coherency)
[56.984084] Subtest coherency: SKIP (0.000s)
[57.041946] [002/155] (960s left) syncobj_eventfd (timeline-wait-before-signal)
[57.106251] Starting subtest: timeline-wait-before-signal
[57.135862] Subtest timeline-wait-before-signal: SUCCESS (0.031s)
[57.212913] [003/155] (960s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-blt)
[57.318553] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-blt
[64.354957] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-blt: SUCCESS (7.051s)
[64.485668] [004/155] (953s left) kms_feature_discovery (chamelium)
[64.597835] Starting subtest: chamelium
[64.602084] Subtest chamelium: SKIP (0.000s)
[64.663344] [005/155] (952s left) gem_ctx_persistence (heartbeat-hang)
[64.731765] Starting subtest: heartbeat-hang
[64.736429] Subtest heartbeat-hang: SKIP (0.000s)
[64.849980] [006/155] (952s left) kms_psr2_sf (psr2-cursor-plane-update-sf)
[64.949703] Starting subtest: psr2-cursor-plane-update-sf
[64.952218] Starting dynamic subtest: pipe-A-eDP-1
[65.530450] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.583s)
[65.546203] Starting dynamic subtest: pipe-B-eDP-1
[66.791298] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.261s)
[66.793888] Subtest psr2-cursor-plane-update-sf: SUCCESS (1.848s)
[67.668343] [007/155] (949s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[67.773596] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[74.437807] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.672s)
[74.571475] [008/155] (943s left) gem_ccs (block-copy-uncompressed)
[74.632059] Starting subtest: block-copy-uncompressed
[74.634489] Starting dynamic subtest: linear-uncompressed-compfmt0-smem-smem
[74.652936] Dynamic subtest linear-uncompressed-compfmt0-smem-smem: SUCCESS (0.023s)
[74.663179] Starting dynamic subtest: xmajor-uncompressed-compfmt0-smem-smem
[74.670398] Dynamic subtest xmajor-uncompressed-compfmt0-smem-smem: SUCCESS (0.017s)
[74.678210] Starting dynamic subtest: tile4-uncompressed-compfmt0-smem-smem
[74.686579] Dynamic subtest tile4-uncompressed-compfmt0-smem-smem: SUCCESS (0.016s)
[74.694847] Starting dynamic subtest: tile64-uncompressed-compfmt0-smem-smem
[74.704043] Dynamic subtest tile64-uncompressed-compfmt0-smem-smem: SUCCESS (0.017s)
[74.706588] Subtest block-copy-uncompressed: SUCCESS (0.075s)
[74.843894] [009/155] (942s left) syncobj_basic (bad-pad-handle-to-fd)
[74.906035] Starting subtest: bad-pad-handle-to-fd
[74.908313] Subtest bad-pad-handle-to-fd: SUCCESS (0.000s)
[75.024169] [010/155] (942s left) kms_flip (flip-vs-fences-interruptible)
[75.117322] Starting subtest: flip-vs-fences-interruptible
[75.121899] Subtest flip-vs-fences-interruptible: SKIP (0.000s)
[75.209655] [011/155] (942s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[75.308374] Starting subtest: fbc-psr2-cursor-plane-update-sf
[75.310667] Starting dynamic subtest: pipe-A-eDP-1
[75.382241] Dynamic subtest pipe-A-eDP-1: SKIP (0.077s)
[75.392489] Starting dynamic subtest: pipe-B-eDP-1
[76.540973] Dynamic subtest pipe-B-eDP-1: SKIP (1.158s)
[76.545652] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (1.237s)
[77.822974] [012/155] (939s left) gem_exercise_blt (fast-copy)
[77.886812] Starting subtest: fast-copy
[77.889173] Starting dynamic subtest: linear-smem-smem
[77.906556] Dynamic subtest linear-smem-smem: SUCCESS (0.022s)
[77.914706] Starting dynamic subtest: xmajor-smem-smem
[77.923683] Dynamic subtest xmajor-smem-smem: SUCCESS (0.017s)
[77.931213] Starting dynamic subtest: tile4-smem-smem
[77.940329] Dynamic subtest tile4-smem-smem: SUCCESS (0.016s)
[77.948083] Starting dynamic subtest: tile64-smem-smem
[77.958028] Dynamic subtest tile64-smem-smem: SUCCESS (0.017s)
[77.960475] Subtest fast-copy: SUCCESS (0.074s)
[78.072913] [013/155] (939s left) prime_busy (hang)
[78.146032] Starting subtest: hang
[78.148096] Starting dynamic subtest: rcs0
[103.840083] Dynamic subtest rcs0: SUCCESS (25.698s)
[103.847662] Starting dynamic subtest: bcs0
[110.920657] Dynamic subtest bcs0: SUCCESS (7.083s)
[110.928736] Starting dynamic subtest: vcs0
[117.965848] Dynamic subtest vcs0: SUCCESS (7.042s)
[117.973427] Starting dynamic subtest: vcs1
[124.938763] Dynamic subtest vcs1: SUCCESS (6.972s)
[124.946155] Starting dynamic subtest: vecs0
[131.979082] Dynamic subtest vecs0: SUCCESS (7.039s)
[131.986775] Starting dynamic subtest: ccs0
[145.816038] Dynamic subtest ccs0: SUCCESS (13.836s)
[145.818427] Subtest hang: SUCCESS (67.674s)
[145.921245] [014/155] (871s left) gem_sync (basic-all)
[145.989833] Starting subtest: basic-all
[149.314522] Subtest basic-all: SUCCESS (3.334s)
[149.503970] [015/155] (868s left) sysfs_preempt_timeout (timeout)
[149.580994] Starting subtest: timeout
[149.583360] Starting dynamic subtest: vecs0
[159.288591] Dynamic subtest vecs0: SUCCESS (9.706s)
[159.298277] Starting dynamic subtest: ccs0
[160.578483] Dynamic subtest ccs0: SUCCESS (1.290s)
[160.587158] Starting dynamic subtest: vcs0
[170.304081] Dynamic subtest vcs0: SUCCESS (9.723s)
[170.314247] Starting dynamic subtest: bcs0
[171.536505] Dynamic subtest bcs0: SUCCESS (1.233s)
[171.544530] Starting dynamic subtest: rcs0
[172.795610] Dynamic subtest rcs0: SUCCESS (1.258s)
[172.804836] Starting dynamic subtest: vcs1
[182.276988] Dynamic subtest vcs1: SUCCESS (9.482s)
[182.279646] Subtest timeout: SUCCESS (32.698s)
[182.351274] [016/155] (835s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-upscaling)
[182.449344] Starting subtest: flip-32bpp-linear-to-64bpp-linear-upscaling
[182.451857] Starting dynamic subtest: pipe-A-default-mode
[185.177112] Dynamic subtest pipe-A-default-mode: SUCCESS (2.725s)
[185.179281] Subtest flip-32bpp-linear-to-64bpp-linear-upscaling: SUCCESS (2.726s)
[186.033453] [017/155] (831s left) gem_pipe_control_store_loop (reused-buffer)
[186.086400] Starting subtest: reused-buffer
[188.240563] Subtest reused-buffer: SUCCESS (2.152s)
[188.344186] [018/155] (829s left) api_intel_allocator (reopen-fork)
[188.393222] Starting subtest: reopen-fork
[191.887135] Subtest reopen-fork: SUCCESS (3.495s)
[191.963061] [019/155] (826s left) gem_exec_balancer (bonded-true-hang)
[192.221825] Starting subtest: bonded-true-hang
[192.227235] Subtest bonded-true-hang: SKIP (0.000s)
[192.348598] [020/155] (825s left) kms_pipe_stress (stress-xrgb8888-ytiled)
[192.447637] Starting subtest: stress-xrgb8888-ytiled
[192.452392] Subtest stress-xrgb8888-ytiled: SKIP (0.000s)
[192.516744] [021/155] (825s left) kms_ccs (bad-rotation-90-4-tiled-dg2-mc-ccs)
[192.612304] Starting subtest: bad-rotation-90-4-tiled-dg2-mc-ccs
[192.614643] Starting dynamic subtest: pipe-A-eDP-1
[192.637677] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[192.647846] Starting dynamic subtest: pipe-B-eDP-1
[192.669349] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[192.679840] Starting dynamic subtest: pipe-C-eDP-1
[192.702668] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[192.711791] Starting dynamic subtest: pipe-D-eDP-1
[192.733812] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[192.738179] Subtest bad-rotation-90-4-tiled-dg2-mc-ccs: SKIP (0.003s)
[194.882183] [022/155] (823s left) kms_flip (2x-flip-vs-fences)
[194.984140] Starting subtest: 2x-flip-vs-fences
[194.988190] Subtest 2x-flip-vs-fences: SKIP (0.000s)
[195.093313] [023/155] (823s left) gem_eio (in-flight-contexts-immediate)
[195.306654] Starting subtest: in-flight-contexts-immediate
[209.300386] Subtest in-flight-contexts-immediate: SUCCESS (13.993s)
[209.482757] [024/155] (808s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-wc)
[209.582833] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-wc
[209.587070] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[209.694514] [025/155] (808s left) gem_userptr_blits (unsync-unmap-after-close)
[209.766471] Subtest unsync-unmap-after-close: SKIP (0.000s)
[209.885230] [026/155] (808s left) kms_vblank (ts-continuation-modeset-rpm)
[209.985273] Starting subtest: ts-continuation-modeset-rpm
[209.987225] Starting dynamic subtest: pipe-A-eDP-1
[212.473832] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.488s)
[212.489151] Starting dynamic subtest: pipe-D-eDP-1
[214.727505] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.252s)
[214.729439] Subtest ts-continuation-modeset-rpm: SUCCESS (4.743s)
[215.611525] [027/155] (802s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-wc)
[215.702676] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-wc
[222.261481] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.561s)
[222.360453] [028/155] (796s left) gem_mmap_gtt (basic-write)
[222.417508] Subtest basic-write: SKIP (0.000s)
[222.472337] [029/155] (796s left) kms_cursor_crc (cursor-sliding-256x256)
[222.729830] Starting subtest: cursor-sliding-256x256
[222.732249] Starting dynamic subtest: pipe-A-eDP-1
[225.980050] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.249s)
[226.008622] Starting dynamic subtest: pipe-D-eDP-1
[229.850073] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.846s)
[229.852399] Subtest cursor-sliding-256x256: SUCCESS (7.120s)
[230.722344] [030/155] (787s left) kms_properties (get_properties-sanity-atomic)
[230.811877] Starting subtest: get_properties-sanity-atomic
[230.814221] Subtest get_properties-sanity-atomic: SUCCESS (0.002s)
[230.895030] [031/155] (787s left) kms_frontbuffer_tracking (hdr-rgb565-draw-pwrite)
[230.985757] Starting subtest: hdr-rgb565-draw-pwrite
[230.990114] Subtest hdr-rgb565-draw-pwrite: SKIP (0.000s)
[231.088868] [032/155] (787s left) i915_drm_fdinfo (virtual-idle)
[231.127842] Starting subtest: virtual-idle
[234.187409] Subtest virtual-idle: SUCCESS (3.060s)
[234.265751] [033/155] (784s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180)
[234.368073] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180
[234.372316] Subtest y-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s)
[234.467806] [034/155] (784s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-shrfb-draw-pwrite)
[234.570133] Starting subtest: fbchdr-2p-scndscrn-pri-shrfb-draw-pwrite
[234.574439] Subtest fbchdr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[234.686748] [035/155] (784s left) perf_pmu (faulting-read)
[234.728428] Starting subtest: faulting-read
[234.730759] Starting dynamic subtest: wb
[234.733031] Dynamic subtest wb: SUCCESS (0.001s)
[234.744138] Starting dynamic subtest: wc
[234.746222] Dynamic subtest wc: SUCCESS (0.000s)
[234.755615] Starting dynamic subtest: uc
[234.757785] Dynamic subtest uc: SUCCESS (0.000s)
[234.759993] Subtest faulting-read: SUCCESS (0.002s)
[234.853548] [036/155] (783s left) prime_mmap (test_invalid_sync_flags)
[234.913283] Starting subtest: test_invalid_sync_flags
[234.915707] Starting dynamic subtest: test_invalid_sync_flags-smem
[234.917998] Dynamic subtest test_invalid_sync_flags-smem: SUCCESS (0.000s)
[234.920323] Subtest test_invalid_sync_flags: SUCCESS (0.000s)
[235.019524] [037/155] (783s left) kms_psr (pr-sprite-plane-onoff)
[235.118923] Starting subtest: pr-sprite-plane-onoff
[235.123155] Subtest pr-sprite-plane-onoff: SKIP (0.000s)
[235.204784] [038/155] (783s left) kms_getfb (getfb2-handle-not-fb)
[235.299468] Starting subtest: getfb2-handle-not-fb
[235.301518] Subtest getfb2-handle-not-fb: SUCCESS (0.000s)
[235.390697] [039/155] (783s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs)
[235.494142] Starting subtest: ccs-on-another-bo-y-tiled-gen12-mc-ccs
[235.496212] Starting dynamic subtest: pipe-A-eDP-1
[235.516060] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[235.529299] Starting dynamic subtest: pipe-B-eDP-1
[235.547320] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[235.556227] Starting dynamic subtest: pipe-C-eDP-1
[235.574164] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[235.584967] Starting dynamic subtest: pipe-D-eDP-1
[235.603361] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[235.606910] Subtest ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.003s)
[237.783484] [040/155] (781s left) gem_close (many-handles-one-vma)
[237.840485] Starting subtest: many-handles-one-vma
[237.864515] Subtest many-handles-one-vma: SUCCESS (0.024s)
[237.978322] [041/155] (780s left) kms_frontbuffer_tracking (hdr-1p-primscrn-cur-indfb-draw-blt)
[238.068417] Starting subtest: hdr-1p-primscrn-cur-indfb-draw-blt
[238.072618] Subtest hdr-1p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[238.163669] [042/155] (780s left) core_getversion (all-cards)
[238.201830] Starting subtest: all-cards
[238.208701] Subtest all-cards: SUCCESS (0.009s)
[238.255974] [043/155] (780s left) syncobj_timeline (multi-wait-all-available-signaled)
[238.315926] Starting subtest: multi-wait-all-available-signaled
[238.318581] Subtest multi-wait-all-available-signaled: SUCCESS (0.000s)
[238.414947] [044/155] (780s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-render)
[238.509063] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-render
[242.678859] Subtest fbc-1p-offscreen-pri-indfb-draw-render: SUCCESS (4.176s)
[243.883331] [045/155] (775s left) syncobj_timeline (single-wait-for-submit-available-submitted)
[243.943078] Starting subtest: single-wait-for-submit-available-submitted
[243.945330] Subtest single-wait-for-submit-available-submitted: SUCCESS (0.000s)
[244.072879] [046/155] (775s left) kms_big_fb (yf-tiled-32bpp-rotate-0)
[244.177899] Starting subtest: yf-tiled-32bpp-rotate-0
[244.181848] Subtest yf-tiled-32bpp-rotate-0: SKIP (0.000s)
[244.275476] [047/155] (774s left) gem_exec_fence (syncobj-repeat)
[244.745643] Starting subtest: syncobj-repeat
[244.936329] Subtest syncobj-repeat: SUCCESS (0.189s)
[246.357457] [FACT gem_exec_fence (syncobj-repeat)] new: kernel.kmod_is_loaded.vgem: true
[246.361090] [048/155] (772s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-rc-ccs)
[246.452158] Starting subtest: crc-primary-suspend-4-tiled-dg2-rc-ccs
[246.454530] Starting dynamic subtest: pipe-A-eDP-1
[246.463979] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[246.475104] Starting dynamic subtest: pipe-B-eDP-1
[246.484434] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[246.493829] Starting dynamic subtest: pipe-C-eDP-1
[246.502829] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[246.512189] Starting dynamic subtest: pipe-D-eDP-1
[246.520701] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[246.525014] Subtest crc-primary-suspend-4-tiled-dg2-rc-ccs: SKIP (0.004s)
[248.752230] [049/155] (770s left) syncobj_timeline (multi-wait-for-submit-available-signaled)
[248.812387] Starting subtest: multi-wait-for-submit-available-signaled
[248.814794] Subtest multi-wait-for-submit-available-signaled: SUCCESS (0.000s)
[248.929545] [050/155] (770s left) kms_atomic (plane-overlay-legacy)
[249.032137] Starting subtest: plane-overlay-legacy
[249.034539] Starting dynamic subtest: pipe-A-eDP-1
[249.432889] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.402s)
[249.435355] Subtest plane-overlay-legacy: SUCCESS (0.404s)
[250.292270] [051/155] (769s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[250.390632] Starting subtest: psrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[250.395051] Subtest psrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[250.496820] [052/155] (768s left) gem_madvise (dontneed-before-pwrite)
[250.531949] Starting subtest: dontneed-before-pwrite
[250.553400] Subtest dontneed-before-pwrite: SKIP (0.018s)
[250.616385] [053/155] (768s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-blt)
[250.719103] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-blt
[250.723471] Subtest fbc-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[250.824343] [054/155] (768s left) kms_cursor_crc (cursor-offscreen-64x21)
[251.039239] Starting subtest: cursor-offscreen-64x21
[251.091810] Subtest cursor-offscreen-64x21: SKIP (0.048s)
[251.356278] [055/155] (768s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[251.447455] Starting subtest: hdr-2p-primscrn-pri-shrfb-draw-mmap-wc
[251.451116] Subtest hdr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[251.543099] [056/155] (768s left) perf_pmu (busy-hang)
[251.583357] Starting subtest: busy-hang
[251.585262] Starting dynamic subtest: rcs0
[252.849557] Dynamic subtest rcs0: SUCCESS (1.263s)
[252.860466] Starting dynamic subtest: bcs0
[254.083401] Dynamic subtest bcs0: SUCCESS (1.235s)
[254.094655] Starting dynamic subtest: vcs0
[255.813074] Dynamic subtest vcs0: SUCCESS (1.728s)
[255.824986] Starting dynamic subtest: vcs1
[257.796232] Dynamic subtest vcs1: SUCCESS (1.978s)
[257.807818] Starting dynamic subtest: vecs0
[259.845712] Dynamic subtest vecs0: SUCCESS (2.048s)
[259.855857] Starting dynamic subtest: ccs0
[261.083420] Dynamic subtest ccs0: SUCCESS (1.238s)
[261.086189] Subtest busy-hang: SUCCESS (9.499s)
[261.186116] [057/155] (758s left) kms_joiner (invalid-modeset-force-big-joiner)
[261.287888] Starting subtest: invalid-modeset-force-big-joiner
[261.292545] Subtest invalid-modeset-force-big-joiner: SKIP (0.000s)
[261.352610] [058/155] (758s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[261.417908] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[261.419855] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[261.519284] [059/155] (758s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc)
[261.619273] Starting subtest: fbc-rgb565-draw-mmap-wc
[265.674647] Subtest fbc-rgb565-draw-mmap-wc: SUCCESS (4.061s)
[266.885532] [060/155] (752s left) gem_exec_params (larger-than-life-batch)
[266.938563] Starting subtest: larger-than-life-batch
[269.140036] Subtest larger-than-life-batch: SUCCESS (2.200s)
[270.324685] [061/155] (749s left) gem_exec_params (no-bsd)
[270.415195] Starting subtest: no-bsd
[270.417601] Subtest no-bsd: SUCCESS (0.004s)
[272.364746] [062/155] (747s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[272.476786] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-mmap-cpu
[272.481223] Subtest fbchdr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[272.554144] [063/155] (747s left) gem_pread (bench)
[272.621618] Subtest bench: SKIP (0.000s)
[272.680501] [064/155] (747s left) kms_big_fb (4-tiled-64bpp-rotate-270)
[272.772357] Starting subtest: 4-tiled-64bpp-rotate-270
[272.791378] Subtest 4-tiled-64bpp-rotate-270: SKIP (0.019s)
[272.895180] [065/155] (747s left) gem_softpin (32b-excludes-last-page)
[272.957261] Starting subtest: 32b-excludes-last-page
[272.962602] Subtest 32b-excludes-last-page: SUCCESS (0.003s)
[273.109063] [066/155] (746s left) kms_atomic_interruptible (atomic-setmode)
[273.222896] Starting subtest: atomic-setmode
[273.225469] Starting dynamic subtest: pipe-A-eDP-1
[280.143862] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.917s)
[280.145843] Subtest atomic-setmode: SUCCESS (6.918s)
[281.027228] [067/155] (739s left) i915_pm_rpm (system-suspend-devices)
[282.580239] Starting subtest: system-suspend-devices
[290.357324] Subtest system-suspend-devices: SUCCESS (7.779s)
[290.749154] [068/155] (729s left) kms_content_protection (dp-mst-type-0)
[290.901091] Starting subtest: dp-mst-type-0
[290.905910] Subtest dp-mst-type-0: SKIP (0.000s)
[291.012481] [069/155] (729s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[291.107971] Starting subtest: fbchdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[291.112108] Subtest fbchdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[291.206251] [070/155] (728s left) kms_psr (fbc-pr-primary-render)
[291.292762] Starting subtest: fbc-pr-primary-render
[291.297171] Subtest fbc-pr-primary-render: SKIP (0.000s)
[291.364733] [071/155] (728s left) kms_plane (pixel-format-4-tiled-modifier)
[291.458221] Starting subtest: pixel-format-4-tiled-modifier
[291.484914] Starting dynamic subtest: pipe-A-plane-0
[291.861893] Dynamic subtest pipe-A-plane-0: SUCCESS (0.377s)
[291.876064] Starting dynamic subtest: pipe-A-plane-3
[292.206559] Dynamic subtest pipe-A-plane-3: SUCCESS (0.343s)
[292.220132] Starting dynamic subtest: pipe-A-plane-5
[292.223830] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[293.330496] Starting dynamic subtest: pipe-B-plane-0
[293.709529] Dynamic subtest pipe-B-plane-0: SUCCESS (0.389s)
[293.721374] Starting dynamic subtest: pipe-B-plane-3
[294.020770] Dynamic subtest pipe-B-plane-3: SUCCESS (0.311s)
[294.031563] Starting dynamic subtest: pipe-B-plane-5
[294.036199] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[294.331185] Subtest pixel-format-4-tiled-modifier: SUCCESS (2.872s)
[295.199586] [072/155] (725s left) kms_flip (nonblocking-read)
[295.307473] Starting subtest: nonblocking-read
[295.309447] Subtest nonblocking-read: SUCCESS (0.000s)
[295.427430] [073/155] (724s left) gem_sync (basic-store-each)
[295.499143] Starting subtest: basic-store-each
[297.827188] Subtest basic-store-each: SUCCESS (2.338s)
[298.022297] [074/155] (722s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-indfb-msflip-blt)
[298.120724] Starting subtest: fbcpsrhdr-1p-primscrn-indfb-msflip-blt
[298.125226] Subtest fbcpsrhdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[298.229973] [075/155] (722s left) gem_mmap_wc (write-gtt-read-wc)
[298.301238] Subtest write-gtt-read-wc: SKIP (0.000s)
[298.367830] [076/155] (722s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-cc-modifier)
[298.456748] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-cc-modifier
[298.487587] Starting dynamic subtest: pipe-A-plane-0
[298.787615] Dynamic subtest pipe-A-plane-0: SUCCESS (0.300s)
[298.801289] Starting dynamic subtest: pipe-A-plane-3
[299.021670] Dynamic subtest pipe-A-plane-3: SUCCESS (0.232s)
[299.032678] Starting dynamic subtest: pipe-A-plane-5
[299.036576] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[300.096494] Starting dynamic subtest: pipe-B-plane-0
[300.376727] Dynamic subtest pipe-B-plane-0: SUCCESS (0.289s)
[300.393137] Starting dynamic subtest: pipe-B-plane-3
[300.621247] Dynamic subtest pipe-B-plane-3: SUCCESS (0.243s)
[300.633179] Starting dynamic subtest: pipe-B-plane-5
[300.637823] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[300.944599] Subtest pixel-format-4-tiled-mtl-rc-ccs-cc-modifier: SUCCESS (2.484s)
[301.774958] [077/155] (718s left) kms_frontbuffer_tracking (fbchdr-2p-indfb-fliptrack-mmap-gtt)
[301.879430] Starting subtest: fbchdr-2p-indfb-fliptrack-mmap-gtt
[301.883875] Subtest fbchdr-2p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[301.978859] [078/155] (718s left) kms_psr (fbc-psr-cursor-plane-onoff)
[302.070327] Starting subtest: fbc-psr-cursor-plane-onoff
[302.072606] Starting dynamic subtest: eDP-1
[302.076640] Dynamic subtest eDP-1: SKIP (0.000s)
[302.080625] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.001s)
[302.144479] [079/155] (718s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[302.229311] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[302.231577] Starting dynamic subtest: pipe-A
[302.249219] Dynamic subtest pipe-A: SKIP (0.016s)
[302.260112] Starting dynamic subtest: pipe-B
[302.278181] Dynamic subtest pipe-B: SKIP (0.030s)
[302.288714] Starting dynamic subtest: pipe-C
[302.312603] Dynamic subtest pipe-C: SKIP (0.033s)
[302.323019] Starting dynamic subtest: pipe-D
[302.341170] Dynamic subtest pipe-D: SKIP (0.028s)
[302.345126] Subtest plane-downscale-factor-0-5-with-modifiers: SKIP (0.110s)
[302.451847] [080/155] (718s left) kms_big_fb (linear-8bpp-rotate-180)
[302.556220] Starting subtest: linear-8bpp-rotate-180
[303.826139] Subtest linear-8bpp-rotate-180: SUCCESS (1.269s)
[303.940436] [081/155] (716s left) gem_mmap (bad-size)
[304.011177] Subtest bad-size: SKIP (0.000s)
[304.075753] [082/155] (716s left) kms_frontbuffer_tracking (psr-rgb565-draw-render)
[304.172790] Starting subtest: psr-rgb565-draw-render
[311.322247] Subtest psr-rgb565-draw-render: SUCCESS (7.153s)
[311.459291] [083/155] (709s left) gem_close_race (multigpu-basic-process)
[311.527457] Starting subtest: multigpu-basic-process
[311.532089] Subtest multigpu-basic-process: SKIP (0.000s)
[311.747937] [084/155] (709s left) gem_ctx_persistence (engines-mixed)
[311.810533] Starting subtest: engines-mixed
[311.813102] Starting dynamic subtest: rcs0
[311.922592] Dynamic subtest rcs0: SUCCESS (0.109s)
[311.932922] Starting dynamic subtest: bcs0
[311.982765] Dynamic subtest bcs0: SUCCESS (0.061s)
[311.995664] Starting dynamic subtest: vcs0
[312.033699] Dynamic subtest vcs0: SUCCESS (0.048s)
[312.044858] Starting dynamic subtest: vcs1
[312.081689] Dynamic subtest vcs1: SUCCESS (0.046s)
[312.093600] Starting dynamic subtest: vecs0
[312.133798] Dynamic subtest vecs0: SUCCESS (0.050s)
[312.147043] Starting dynamic subtest: ccs0
[312.182801] Dynamic subtest ccs0: SUCCESS (0.047s)
[312.185250] Subtest engines-mixed: SUCCESS (0.373s)
[312.268741] [085/155] (708s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[312.362468] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[312.366499] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[312.484133] [086/155] (708s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-shrfb-draw-pwrite)
[312.589212] Starting subtest: hdr-1p-offscreen-pri-shrfb-draw-pwrite
[312.593652] Subtest hdr-1p-offscreen-pri-shrfb-draw-pwrite: SKIP (0.000s)
[312.695649] [087/155] (708s left) kms_cursor_legacy (torture-bo)
[312.815753] Starting subtest: torture-bo
[312.817746] Starting dynamic subtest: pipe-A
[318.637743] Dynamic subtest pipe-A: SUCCESS (5.817s)
[318.651584] Starting dynamic subtest: pipe-B
[325.102086] Dynamic subtest pipe-B: SUCCESS (6.461s)
[325.127667] Starting dynamic subtest: pipe-C
[331.555612] Dynamic subtest pipe-C: SUCCESS (6.448s)
[331.568592] Starting dynamic subtest: pipe-D
[339.568025] Dynamic subtest pipe-D: SUCCESS (8.013s)
[339.580702] Starting dynamic subtest: all-pipes
[347.553882] Dynamic subtest all-pipes: SUCCESS (7.983s)
[347.556671] Subtest torture-bo: SUCCESS (34.734s)
[347.717170] [088/155] (673s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[347.833838] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[347.838114] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[347.945181] [089/155] (673s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-render)
[348.052160] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-render
[348.056737] Subtest psr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[348.141438] [090/155] (672s left) gem_exec_flush (basic-batch-kernel-default-uc)
[348.192514] Starting subtest: basic-batch-kernel-default-uc
[355.626762] Subtest basic-batch-kernel-default-uc: SUCCESS (7.434s)
[355.818105] [091/155] (665s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-draw-blt)
[355.919660] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-draw-blt
[355.923722] Subtest psrhdr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[356.016975] [092/155] (665s left) perf (invalid-oa-metric-set-id)
[356.165130] Starting subtest: invalid-oa-metric-set-id
[356.175407] Subtest invalid-oa-metric-set-id: SUCCESS (0.012s)
[356.216880] [093/155] (664s left) gem_softpin (reverse)
[356.280084] Starting subtest: reverse
[356.283830] Subtest reverse: SUCCESS (0.006s)
[356.417163] [094/155] (664s left) gem_tiled_fence_blits (basic)
[356.479549] Subtest basic: SKIP (0.000s)
[356.546041] [095/155] (664s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-cur-indfb-draw-render)
[356.650053] Starting subtest: fbchdr-1p-primscrn-cur-indfb-draw-render
[356.654271] Subtest fbchdr-1p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[356.755213] [096/155] (664s left) kms_cursor_crc (cursor-sliding-512x512)
[357.015510] Starting subtest: cursor-sliding-512x512
[357.019217] Subtest cursor-sliding-512x512: SKIP (0.001s)
[357.183129] [097/155] (664s left) gem_exec_whisper (basic-contexts-forked-all)
[357.243408] Starting subtest: basic-contexts-forked-all
[378.122879] Subtest basic-contexts-forked-all: SUCCESS (20.873s)
[378.306415] [098/155] (643s left) gem_exec_capture (capture)
[378.365063] Starting subtest: capture
[378.367321] Starting dynamic subtest: rcs0-smem
[379.199694] Dynamic subtest rcs0-smem: SUCCESS (0.834s)
[379.216790] Starting dynamic subtest: bcs0-smem
[380.021267] Dynamic subtest bcs0-smem: SUCCESS (0.819s)
[380.031681] Starting dynamic subtest: vcs0-smem
[380.844712] Dynamic subtest vcs0-smem: SUCCESS (0.821s)
[380.855284] Starting dynamic subtest: vcs1-smem
[382.159907] Dynamic subtest vcs1-smem: SUCCESS (1.317s)
[382.173775] Starting dynamic subtest: vecs0-smem
[383.063588] Dynamic subtest vecs0-smem: FAIL (0.837s)
[383.079454] Starting dynamic subtest: ccs0-smem
[384.322314] Dynamic subtest ccs0-smem: SUCCESS (1.321s)
[384.324799] Subtest capture: FAIL (5.955s)
[384.469076] [099/155] (636s left) syncobj_basic (create-signaled)
[384.533636] Starting subtest: create-signaled
[384.535953] Subtest create-signaled: SUCCESS (0.000s)
[384.637263] [100/155] (636s left) kms_big_fb (y-tiled-8bpp-rotate-180)
[384.752066] Starting subtest: y-tiled-8bpp-rotate-180
[384.756488] Subtest y-tiled-8bpp-rotate-180: SKIP (0.000s)
[384.855063] [101/155] (636s left) gem_userptr_blits (sd-probe)
[384.919674] Starting subtest: sd-probe
[384.921547] Subtest sd-probe: SUCCESS (0.000s)
[385.042952] [102/155] (636s left) i915_suspend (forcewake)
[385.094231] Starting subtest: forcewake
[417.334190] Subtest forcewake: SUCCESS (2.267s)
[418.407184] [103/155] (604s left) gem_userptr_blits (sync-unmap-after-close)
[418.476334] Starting subtest: sync-unmap-after-close
[418.484762] Subtest sync-unmap-after-close: SUCCESS (0.011s)
[418.615740] [104/155] (604s left) drm_mm (drm_mm)
[418.654738] Starting subtest: drm_mm
[418.729204] Starting dynamic subtest: drm_test_mm_init
[418.843484] Dynamic subtest drm_test_mm_init: SUCCESS (0.112s)
[418.854454] Starting dynamic subtest: drm_test_mm_debug
[418.939066] Dynamic subtest drm_test_mm_debug: SUCCESS (0.097s)
[418.950002] Starting dynamic subtest: drm_test_mm_align32
[419.030234] Dynamic subtest drm_test_mm_align32: SUCCESS (0.087s)
[419.041131] Starting dynamic subtest: drm_test_mm_align64
[419.135981] Dynamic subtest drm_test_mm_align64: SUCCESS (0.105s)
[419.146293] Starting dynamic subtest: drm_test_mm_lowest
[419.231935] Dynamic subtest drm_test_mm_lowest: SUCCESS (0.095s)
[419.242278] Starting dynamic subtest: drm_test_mm_highest
[419.327659] Dynamic subtest drm_test_mm_highest: SUCCESS (0.094s)
[419.329510] Subtest drm_mm: SUCCESS (0.673s)
[419.492149] [105/155] (603s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-indfb-pgflip-blt)
[419.593427] Starting subtest: psrhdr-1p-primscrn-indfb-pgflip-blt
[419.597440] Subtest psrhdr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[419.701637] [106/155] (603s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[419.801060] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[419.805145] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[419.896749] [107/155] (602s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-move)
[419.993521] Starting subtest: fbc-2p-primscrn-cur-indfb-move
[419.997133] Subtest fbc-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[420.101280] [108/155] (602s left) gem_exec_params (invalid-bsd2-flag-on-render)
[420.160725] Starting subtest: invalid-bsd2-flag-on-render
[420.162388] Subtest invalid-bsd2-flag-on-render: SUCCESS (0.000s)
[420.261315] [109/155] (602s left) gem_partial_pwrite_pread (reads-snoop)
[420.319674] Subtest reads-snoop: SKIP (0.000s)
[420.399166] [110/155] (602s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[420.512810] Starting subtest: basic-busy-flip-before-cursor-legacy
[420.516629] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[420.679443] [111/155] (602s left) kms_frontbuffer_tracking (fbcpsrhdr-farfromfence-mmap-gtt)
[420.774498] Starting subtest: fbcpsrhdr-farfromfence-mmap-gtt
[420.779115] Subtest fbcpsrhdr-farfromfence-mmap-gtt: SKIP (0.000s)
[420.881500] [112/155] (602s left) kms_flip (2x-flip-vs-modeset)
[420.983589] Starting subtest: 2x-flip-vs-modeset
[420.992354] Subtest 2x-flip-vs-modeset: SKIP (0.010s)
[421.106330] [113/155] (601s left) kms_big_fb (x-tiled-16bpp-rotate-180)
[421.213500] Starting subtest: x-tiled-16bpp-rotate-180
[422.470055] Subtest x-tiled-16bpp-rotate-180: SUCCESS (1.257s)
[422.617944] [114/155] (600s left) kms_frontbuffer_tracking (hdr-1p-primscrn-spr-indfb-move)
[422.721219] Starting subtest: hdr-1p-primscrn-spr-indfb-move
[422.724777] Subtest hdr-1p-primscrn-spr-indfb-move: SKIP (0.000s)
[422.837055] [115/155] (600s left) kms_vblank (query-forked-hang)
[422.934227] Starting subtest: query-forked-hang
[422.936253] Starting dynamic subtest: pipe-A-eDP-1
[449.026012] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.103s)
[449.043133] Starting dynamic subtest: pipe-D-eDP-1
[473.985006] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.962s)
[473.987530] Subtest query-forked-hang: SUCCESS (51.069s)
[474.812336] [116/155] (548s left) kms_lease (lease-revoke)
[474.924000] Starting subtest: lease-revoke
[474.926481] Starting dynamic subtest: pipe-A-eDP-1
[474.959219] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.034s)
[474.970963] Starting dynamic subtest: pipe-B-eDP-1
[474.985479] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.027s)
[475.000626] Starting dynamic subtest: pipe-C-eDP-1
[475.011912] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.025s)
[475.024638] Starting dynamic subtest: pipe-D-eDP-1
[475.039504] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.026s)
[475.042032] Subtest lease-revoke: SUCCESS (0.118s)
[475.156315] [117/155] (548s left) i915_drm_fdinfo (busy-idle-check-all)
[475.198373] Starting subtest: busy-idle-check-all
[475.200631] Starting dynamic subtest: rcs0
[475.205460] Dynamic subtest rcs0: SKIP (0.005s)
[475.217643] Starting dynamic subtest: bcs0
[475.222416] Dynamic subtest bcs0: SKIP (0.000s)
[475.234096] Starting dynamic subtest: vcs0
[475.238560] Dynamic subtest vcs0: SKIP (0.000s)
[475.249176] Starting dynamic subtest: vcs1
[475.253505] Dynamic subtest vcs1: SKIP (0.000s)
[475.263960] Starting dynamic subtest: vecs0
[475.268349] Dynamic subtest vecs0: SKIP (0.000s)
[475.278930] Starting dynamic subtest: ccs0
[475.283293] Dynamic subtest ccs0: SKIP (0.000s)
[475.287763] Subtest busy-idle-check-all: SKIP (0.010s)
[475.328145] [118/155] (547s left) gem_exec_schedule (submit-early-slice)
[475.416154] Starting subtest: submit-early-slice
[475.418130] Starting dynamic subtest: rcs0
[475.452249] Dynamic subtest rcs0: SUCCESS (0.043s)
[475.464502] Starting dynamic subtest: bcs0
[475.490472] Dynamic subtest bcs0: SUCCESS (0.038s)
[475.501746] Starting dynamic subtest: vcs0
[475.530143] Dynamic subtest vcs0: SUCCESS (0.038s)
[475.545526] Starting dynamic subtest: vcs1
[475.572462] Dynamic subtest vcs1: SUCCESS (0.041s)
[475.583525] Starting dynamic subtest: vecs0
[475.609870] Dynamic subtest vecs0: SUCCESS (0.037s)
[475.626354] Starting dynamic subtest: ccs0
[475.649778] Dynamic subtest ccs0: SUCCESS (0.039s)
[475.652059] Subtest submit-early-slice: SUCCESS (0.242s)
[477.336618] [119/155] (545s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-downscaling)
[477.426852] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-downscaling
[477.429267] Starting dynamic subtest: pipe-A-default-mode
[478.578130] Dynamic subtest pipe-A-default-mode: SKIP (1.149s)
[478.582336] Subtest flip-64bpp-4tile-to-16bpp-4tile-downscaling: SKIP (1.150s)
[478.765560] [120/155] (544s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-spr-indfb-draw-pwrite)
[478.871500] Starting subtest: psrhdr-1p-primscrn-spr-indfb-draw-pwrite
[478.875738] Subtest psrhdr-1p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[478.973075] [121/155] (544s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-indfb-draw-pwrite)
[479.067825] Starting subtest: fbcpsrhdr-1p-offscreen-pri-indfb-draw-pwrite
[479.071886] Subtest fbcpsrhdr-1p-offscreen-pri-indfb-draw-pwrite: SKIP (0.000s)
[479.168462] [122/155] (544s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-shrfb-fliptrack-mmap-gtt)
[479.267174] Starting subtest: fbcpsrhdr-1p-shrfb-fliptrack-mmap-gtt
[479.271375] Subtest fbcpsrhdr-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[479.361248] [123/155] (544s left) gen9_exec_parse (secure-batches)
[479.409755] Subtest secure-batches: SKIP (0.000s)
[479.479537] [124/155] (544s left) kms_plane_scaling (planes-upscale-factor-0-25)
[479.561350] Starting subtest: planes-upscale-factor-0-25
[479.563204] Starting dynamic subtest: pipe-A
[479.620987] Dynamic subtest pipe-A: SUCCESS (0.062s)
[479.633491] Starting dynamic subtest: pipe-B
[480.765472] Dynamic subtest pipe-B: SUCCESS (1.143s)
[480.779149] Starting dynamic subtest: pipe-C
[481.873240] Dynamic subtest pipe-C: SUCCESS (1.107s)
[481.886446] Starting dynamic subtest: pipe-D
[483.004238] Dynamic subtest pipe-D: SUCCESS (1.131s)
[483.006602] Subtest planes-upscale-factor-0-25: SUCCESS (3.446s)
[484.166269] [125/155] (539s left) gem_eio (in-flight-internal-10ms)
[484.369497] Starting subtest: in-flight-internal-10ms
[486.345579] Subtest in-flight-internal-10ms: SUCCESS (1.976s)
[486.475951] [126/155] (537s left) kms_big_fb (yf-tiled-64bpp-rotate-0)
[486.578732] Starting subtest: yf-tiled-64bpp-rotate-0
[486.583236] Subtest yf-tiled-64bpp-rotate-0: SKIP (0.000s)
[486.689831] [127/155] (536s left) kms_chamelium_hpd (vga-hpd-without-ddc)
[487.062626] Subtest vga-hpd-without-ddc: SKIP (0.000s)
[487.883801] [128/155] (535s left) kms_psr (fbc-pr-sprite-render)
[487.973731] Starting subtest: fbc-pr-sprite-render
[487.980277] Subtest fbc-pr-sprite-render: SKIP (0.000s)
[488.064083] [129/155] (535s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[488.172466] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[488.176796] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[488.286874] [130/155] (535s left) kms_chamelium_color (degamma)
[488.393808] Subtest degamma: SKIP (0.000s)
[488.439950] [131/155] (535s left) gem_render_copy (yf-tiled-ccs-to-x-tiled)
[488.497470] Starting subtest: yf-tiled-ccs-to-x-tiled
[488.502118] Subtest yf-tiled-ccs-to-x-tiled: SKIP (0.000s)
[488.678967] [132/155] (535s left) kms_frontbuffer_tracking (fbc-suspend)
[488.786841] Starting subtest: fbc-suspend
[526.817112] Subtest fbc-suspend: SUCCESS (7.491s)
[528.011353] [133/155] (495s left) gem_exec_schedule (pi-userfault)
[528.199897] Starting subtest: pi-userfault
[528.201917] Starting dynamic subtest: rcs0
[528.206790] Dynamic subtest rcs0: SUCCESS (0.010s)
[528.219060] Starting dynamic subtest: bcs0
[528.220860] Dynamic subtest bcs0: SUCCESS (0.008s)
[528.232264] Starting dynamic subtest: vcs0
[528.234437] Dynamic subtest vcs0: SUCCESS (0.007s)
[528.245973] Starting dynamic subtest: vcs1
[528.247810] Dynamic subtest vcs1: SUCCESS (0.007s)
[528.258345] Starting dynamic subtest: vecs0
[528.260281] Dynamic subtest vecs0: SUCCESS (0.006s)
[528.270798] Starting dynamic subtest: ccs0
[528.272699] Dynamic subtest ccs0: SUCCESS (0.007s)
[528.274741] Subtest pi-userfault: SUCCESS (0.050s)
[528.426245] [134/155] (495s left) gem_mmap_gtt (big-bo-tiledy)
[528.484449] Subtest big-bo-tiledY: SKIP (0.000s)
[528.540156] [135/155] (495s left) kms_psr (psr-no-drrs)
[528.638827] Starting subtest: psr-no-drrs
[528.641089] Starting dynamic subtest: eDP-1
[530.275919] Dynamic subtest eDP-1: SUCCESS (1.640s)
[530.278233] Subtest psr-no-drrs: SUCCESS (1.641s)
[531.177405] [136/155] (492s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-pwrite)
[531.280106] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-pwrite
[539.951013] Subtest psr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.684s)
[540.066168] [137/155] (483s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[540.173008] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[540.177231] Subtest hdr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[540.266185] [138/155] (483s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-indfb-draw-render)
[540.378766] Starting subtest: psrhdr-1p-offscreen-pri-indfb-draw-render
[540.383011] Subtest psrhdr-1p-offscreen-pri-indfb-draw-render: SKIP (0.000s)
[540.465828] [139/155] (483s left) gem_mmap_offset (close-race)
[540.530996] Starting subtest: close-race
[560.643799] Subtest close-race: SUCCESS (20.125s)
[560.776103] [140/155] (463s left) syncobj_basic (bad-create-flags)
[560.831411] Starting subtest: bad-create-flags
[560.833778] Subtest bad-create-flags: SUCCESS (0.000s)
[560.927750] [141/155] (463s left) gem_ctx_shared (q-smoketest)
[561.003134] Starting subtest: Q-smoketest
[561.005511] Starting dynamic subtest: rcs0
[566.543087] Dynamic subtest rcs0: SUCCESS (5.495s)
[566.558509] Starting dynamic subtest: bcs0
[572.047039] Dynamic subtest bcs0: SUCCESS (5.505s)
[572.060046] Starting dynamic subtest: vcs0
[577.523774] Dynamic subtest vcs0: SUCCESS (5.481s)
[577.537060] Starting dynamic subtest: vcs1
[582.998160] Dynamic subtest vcs1: SUCCESS (5.469s)
[583.011986] Starting dynamic subtest: vecs0
[588.473123] Dynamic subtest vecs0: SUCCESS (5.475s)
[588.491302] Starting dynamic subtest: ccs0
[593.985095] Dynamic subtest ccs0: SUCCESS (5.515s)
[593.987140] Subtest Q-smoketest: SUCCESS (32.947s)
[594.139025] [142/155] (430s left) syncobj_wait (multi-wait-for-submit-submitted)
[594.192475] Starting subtest: multi-wait-for-submit-submitted
[594.296684] Subtest multi-wait-for-submit-submitted: SUCCESS (0.104s)
[594.375828] [143/155] (429s left) gem_userptr_blits (map-fixed-invalidate-busy)
[594.441299] Starting subtest: map-fixed-invalidate-busy
[594.445842] Subtest map-fixed-invalidate-busy: SKIP (0.000s)
[594.555285] [144/155] (429s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[594.657737] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[594.661817] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[594.749183] [145/155] (429s left) kms_pm_dc (dc6-dpms)
[595.853730] Starting subtest: dc6-dpms
[597.926083] Subtest dc6-dpms: SUCCESS (2.072s)
[598.020777] [146/155] (426s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[598.115064] Starting subtest: psrhdr-2p-primscrn-pri-indfb-draw-mmap-gtt
[598.119111] Subtest psrhdr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[598.227876] [147/155] (426s left) kms_color (ctm-signed)
[598.317098] Starting subtest: ctm-signed
[598.318989] Starting dynamic subtest: pipe-A-eDP-1
[600.470189] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.155s)
[600.481810] Starting dynamic subtest: pipe-B-eDP-1
[603.342605] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.873s)
[603.354395] Starting dynamic subtest: pipe-C-eDP-1
[606.048451] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.704s)
[606.060501] Starting dynamic subtest: pipe-D-eDP-1
[608.711884] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.667s)
[608.714283] Subtest ctm-signed: SUCCESS (10.402s)
[609.535796] [148/155] (414s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-shrfb-draw-render)
[609.635706] Starting subtest: fbcpsrhdr-2p-primscrn-pri-shrfb-draw-render
[609.639908] Subtest fbcpsrhdr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[609.732425] [149/155] (414s left) gem_exec_await (wide-contexts)
[609.967670] Starting subtest: wide-contexts
[633.590274] Subtest wide-contexts: SUCCESS (23.595s)
[633.793237] [150/155] (390s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc)
[633.934801] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc
[633.937173] Starting dynamic subtest: pipe-A-eDP-1
[634.441374] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.477s)
[634.453870] Starting dynamic subtest: pipe-B-eDP-1
[635.912095] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.468s)
[635.925514] Starting dynamic subtest: pipe-C-eDP-1
[637.261489] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.348s)
[637.275483] Starting dynamic subtest: pipe-D-eDP-1
[638.628095] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.364s)
[638.630080] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (4.661s)
[639.797337] [151/155] (384s left) kms_chamelium_color (ctm-0-25)
[639.909268] Subtest ctm-0-25: SKIP (0.000s)
[639.957355] [152/155] (384s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[640.055126] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-wc
[640.059236] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[640.147831] [153/155] (384s left) kms_3d (basic)
[640.206281] Starting subtest: basic
[642.455012] Subtest basic: SUCCESS (2.249s)
[642.932311] [154/155] (381s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt)
[643.030414] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt
[650.201869] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (7.173s)
[650.338853] [155/155] (374s left) kms_psr_stress_test (invalidate-primary-flip-overlay)
[650.445714] Starting subtest: invalidate-primary-flip-overlay
[661.163395] Subtest invalidate-primary-flip-overlay: SUCCESS (10.717s)
[661.267203] Closing watchdogs
results: parsing output: 0/ for test: gem_mmap_gtt
results: parsing output: 1/ for test: syncobj_eventfd
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_feature_discovery
results: parsing output: 4/ for test: gem_ctx_persistence
results: parsing output: 5/ for test: kms_psr2_sf
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: gem_ccs
results: parsing output: 8/ for test: syncobj_basic
results: parsing output: 9/ for test: kms_flip
results: parsing output: 10/ for test: kms_psr2_sf
results: parsing output: 11/ for test: gem_exercise_blt
results: parsing output: 12/ for test: prime_busy
results: parsing output: 13/ for test: gem_sync
results: parsing output: 14/ for test: sysfs_preempt_timeout
results: parsing output: 15/ for test: kms_flip_scaled_crc
results: parsing output: 16/ for test: gem_pipe_control_store_loop
results: parsing output: 17/ for test: api_intel_allocator
results: parsing output: 18/ for test: gem_exec_balancer
results: parsing output: 19/ for test: kms_pipe_stress
results: parsing output: 20/ for test: kms_ccs
results: parsing output: 21/ for test: kms_flip
results: parsing output: 22/ for test: gem_eio
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: gem_userptr_blits
results: parsing output: 25/ for test: kms_vblank
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: gem_mmap_gtt
results: parsing output: 28/ for test: kms_cursor_crc
results: parsing output: 29/ for test: kms_properties
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: i915_drm_fdinfo
results: parsing output: 32/ for test: kms_big_fb
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: perf_pmu
results: parsing output: 35/ for test: prime_mmap
results: parsing output: 36/ for test: kms_psr
results: parsing output: 37/ for test: kms_getfb
results: parsing output: 38/ for test: kms_ccs
results: parsing output: 39/ for test: gem_close
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: core_getversion
results: parsing output: 42/ for test: syncobj_timeline
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: syncobj_timeline
results: parsing output: 45/ for test: kms_big_fb
results: parsing output: 46/ for test: gem_exec_fence
results: parsing output: 47/ for test: kms_ccs
results: parsing output: 48/ for test: syncobj_timeline
results: parsing output: 49/ for test: kms_atomic
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: gem_madvise
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: kms_cursor_crc
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: perf_pmu
results: parsing output: 56/ for test: kms_joiner
results: parsing output: 57/ for test: syncobj_timeline
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: gem_exec_params
results: parsing output: 60/ for test: gem_exec_params
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: gem_pread
results: parsing output: 63/ for test: kms_big_fb
results: parsing output: 64/ for test: gem_softpin
results: parsing output: 65/ for test: kms_atomic_interruptible
results: parsing output: 66/ for test: i915_pm_rpm
results: parsing output: 67/ for test: kms_content_protection
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: kms_psr
results: parsing output: 70/ for test: kms_plane
results: parsing output: 71/ for test: kms_flip
results: parsing output: 72/ for test: gem_sync
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: gem_mmap_wc
results: parsing output: 75/ for test: kms_plane
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: kms_psr
results: parsing output: 78/ for test: kms_plane_scaling
results: parsing output: 79/ for test: kms_big_fb
results: parsing output: 80/ for test: gem_mmap
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: gem_close_race
results: parsing output: 83/ for test: gem_ctx_persistence
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: kms_cursor_legacy
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: gem_exec_flush
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: perf
results: parsing output: 92/ for test: gem_softpin
results: parsing output: 93/ for test: gem_tiled_fence_blits
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: kms_cursor_crc
results: parsing output: 96/ for test: gem_exec_whisper
results: parsing output: 97/ for test: gem_exec_capture
results: parsing output: 98/ for test: syncobj_basic
results: parsing output: 99/ for test: kms_big_fb
results: parsing output: 100/ for test: gem_userptr_blits
results: parsing output: 101/ for test: i915_suspend
results: parsing output: 102/ for test: gem_userptr_blits
results: parsing output: 103/ for test: drm_mm
results: parsing output: 104/ for test: kms_frontbuffer_tracking
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: gem_exec_params
results: parsing output: 108/ for test: gem_partial_pwrite_pread
results: parsing output: 109/ for test: kms_cursor_legacy
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: kms_flip
results: parsing output: 112/ for test: kms_big_fb
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: kms_vblank
results: parsing output: 115/ for test: kms_lease
results: parsing output: 116/ for test: i915_drm_fdinfo
results: parsing output: 117/ for test: gem_exec_schedule
results: parsing output: 118/ for test: kms_flip_scaled_crc
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: gen9_exec_parse
results: parsing output: 123/ for test: kms_plane_scaling
results: parsing output: 124/ for test: gem_eio
results: parsing output: 125/ for test: kms_big_fb
results: parsing output: 126/ for test: kms_chamelium_hpd
results: parsing output: 127/ for test: kms_psr
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: kms_chamelium_color
results: parsing output: 130/ for test: gem_render_copy
results: parsing output: 131/ for test: kms_frontbuffer_tracking
results: parsing output: 132/ for test: gem_exec_schedule
results: parsing output: 133/ for test: gem_mmap_gtt
results: parsing output: 134/ for test: kms_psr
results: parsing output: 135/ for test: kms_frontbuffer_tracking
results: parsing output: 136/ for test: kms_frontbuffer_tracking
results: parsing output: 137/ for test: kms_frontbuffer_tracking
results: parsing output: 138/ for test: gem_mmap_offset
results: parsing output: 139/ for test: syncobj_basic
results: parsing output: 140/ for test: gem_ctx_shared
results: parsing output: 141/ for test: syncobj_wait
results: parsing output: 142/ for test: gem_userptr_blits
results: parsing output: 143/ for test: kms_frontbuffer_tracking
results: parsing output: 144/ for test: kms_pm_dc
results: parsing output: 145/ for test: kms_frontbuffer_tracking
results: parsing output: 146/ for test: kms_color
results: parsing output: 147/ for test: kms_frontbuffer_tracking
results: parsing output: 148/ for test: gem_exec_await
results: parsing output: 149/ for test: kms_ccs
results: parsing output: 150/ for test: kms_chamelium_color
results: parsing output: 151/ for test: kms_frontbuffer_tracking
results: parsing output: 152/ for test: kms_3d
results: parsing output: 153/ for test: kms_frontbuffer_tracking
results: parsing output: 154/ for test: kms_psr_stress_test
Done.