[61.771957] Initializing watchdogs
[61.772029]   /dev/watchdog0
[61.790885] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:03:00.0: 8086:56a0 Intel Dg2 (Gen12) DG2 [Arc A770]
[61.801145] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[61.804681] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[61.807144] [001/130] (960s left) kms_psr (pr-sprite-mmap-gtt)
[62.099054] Subtest pr-sprite-mmap-gtt: SKIP (0.000s)
[62.152171] [002/130] (960s left) kms_selftest (drm_format_helper)
[62.201251] Starting subtest: drm_format_helper
[62.206786] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8
[62.302818] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8: SUCCESS (0.094s)
[62.333255] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332
[62.401662] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332: SUCCESS (0.099s)
[62.432719] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565
[62.491338] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565: SUCCESS (0.088s)
[62.521975] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555
[62.575918] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555: SUCCESS (0.084s)
[62.605223] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555
[62.684389] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555: SUCCESS (0.106s)
[62.714220] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551
[62.780980] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551: SUCCESS (0.096s)
[62.810480] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888
[62.874543] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888: SUCCESS (0.092s)
[62.905719] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888
[62.990079] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888: SUCCESS (0.116s)
[63.019637] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888
[63.102027] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888: SUCCESS (0.109s)
[63.132659] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010
[63.194659] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010: SUCCESS (0.093s)
[63.224610] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010
[63.288651] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010: SUCCESS (0.094s)
[63.318798] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_mono
[63.389483] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_mono: SUCCESS (0.100s)
[63.419571] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_swab
[63.502111] Dynamic subtest drm_format_helper_test-drm_test_fb_swab: SUCCESS (0.111s)
[63.532648] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888
[63.632259] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888: SUCCESS (0.129s)
[63.661459] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888
[63.736725] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888: SUCCESS (0.104s)
[63.766035] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_clip_offset
[63.854888] Dynamic subtest drm_format_helper_test-drm_test_fb_clip_offset: SUCCESS (0.116s)
[63.884553] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_build_fourcc_list
[63.988366] Dynamic subtest drm_format_helper_test-drm_test_fb_build_fourcc_list: SUCCESS (0.135s)
[64.003680] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_memcpy
[64.106985] Dynamic subtest drm_format_helper_test-drm_test_fb_memcpy: SUCCESS (0.117s)
[64.108898] Subtest drm_format_helper: SUCCESS (1.905s)
[64.317312] [003/130] (958s left) gem_exec_balancer (full-pulse)
[64.405486] Starting subtest: full-pulse
[66.306272] Subtest full-pulse: SUCCESS (1.901s)
[66.684014] [004/130] (955s left) kms_addfb_basic (clobberred-modifier)
[66.757226] Starting subtest: clobberred-modifier
[66.758972] Subtest clobberred-modifier: SKIP (0.000s)
[67.287633] [005/130] (955s left) gem_mmap_gtt (cpuset-medium-copy-odd)
[67.433439] Subtest cpuset-medium-copy-odd: SKIP (0.000s)
[67.581284] [006/130] (955s left) kms_rotation_crc (primary-yf-tiled-reflect-x-0)
[67.839192] Starting subtest: primary-yf-tiled-reflect-x-0
[67.954847] Subtest primary-yf-tiled-reflect-x-0: SKIP (0.113s)
[68.102995] [007/130] (954s left) gem_mmap_wc (pf-nonblock)
[68.180066] Subtest pf-nonblock: SKIP (0.000s)
[68.270792] [008/130] (954s left) gem_render_linear_blits (basic)
[68.349113] Starting subtest: basic
[68.419071] Subtest basic: SUCCESS (0.070s)
[68.504725] [009/130] (954s left) kms_big_fb (linear-64bpp-rotate-180)
[68.715155] Starting subtest: linear-64bpp-rotate-180
[70.668791] Subtest linear-64bpp-rotate-180: SUCCESS (1.953s)
[70.848210] [010/130] (951s left) api_intel_bb (offset-control)
[70.918150] Starting subtest: offset-control
[70.947692] Subtest offset-control: SUCCESS (0.030s)
[71.170848] [011/130] (951s left) perf (blocking)
[71.508857] Starting subtest: blocking
[71.509690] Starting dynamic subtest: 0-rcs0
[81.547443] Dynamic subtest 0-rcs0: SUCCESS (10.038s)
[81.548408] Subtest blocking: SUCCESS (10.038s)
[81.591730] [012/130] (941s left) gem_create (create-ext-placement-sanity-check)
[81.785804] Starting subtest: create-ext-placement-sanity-check
[81.794847] Subtest create-ext-placement-sanity-check: SUCCESS (0.010s)
[81.910726] [013/130] (941s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[82.119534] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-gtt
[82.121273] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[82.251495] [014/130] (940s left) gem_exec_params (cliprects_ptr-dirt)
[82.330547] Starting subtest: cliprects_ptr-dirt
[82.331584] Subtest cliprects_ptr-dirt: SUCCESS (0.000s)
[82.490798] [015/130] (940s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-5)
[82.693163] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-5
[82.694023] Starting dynamic subtest: pipe-A
[82.801514] Dynamic subtest pipe-A: SUCCESS (0.108s)
[82.811136] Starting dynamic subtest: pipe-B
[83.023244] Dynamic subtest pipe-B: SUCCESS (0.221s)
[83.038221] Starting dynamic subtest: pipe-C
[83.264276] Dynamic subtest pipe-C: SUCCESS (0.241s)
[83.274556] Starting dynamic subtest: pipe-D
[83.467459] Dynamic subtest pipe-D: SUCCESS (0.203s)
[83.468359] Subtest planes-upscale-factor-0-25-downscale-factor-0-5: SUCCESS (0.774s)
[83.782265] [016/130] (939s left) kms_atomic_transition (modeset-transition-nonblocking)
[83.984453] Starting subtest: modeset-transition-nonblocking
[83.985409] Starting dynamic subtest: 1x-outputs
[86.610128] Dynamic subtest 1x-outputs: SUCCESS (2.624s)
[86.665193] Subtest modeset-transition-nonblocking: SUCCESS (2.680s)
[86.928633] [017/130] (936s left) i915_selftest (mock)
[88.274128] Starting subtest: mock
[88.275424] Starting dynamic subtest: sanitycheck
[89.055097] Dynamic subtest sanitycheck: SUCCESS (0.780s)
[89.066718] Starting dynamic subtest: shmem
[89.889956] Dynamic subtest shmem: SUCCESS (0.834s)
[89.901663] Starting dynamic subtest: fence
[98.146063] Dynamic subtest fence: SUCCESS (8.255s)
[98.157171] Starting dynamic subtest: scatterlist
[99.884495] Dynamic subtest scatterlist: SUCCESS (1.738s)
[99.895698] Starting dynamic subtest: syncmap
[102.816505] Dynamic subtest syncmap: SUCCESS (2.931s)
[102.825528] Starting dynamic subtest: uncore
[103.527426] Dynamic subtest uncore: SUCCESS (0.710s)
[103.538667] Starting dynamic subtest: ring
[104.289577] Dynamic subtest ring: SUCCESS (0.762s)
[104.297762] Starting dynamic subtest: engine
[105.053520] Dynamic subtest engine: SUCCESS (0.763s)
[105.064888] Starting dynamic subtest: timelines
[109.084355] Dynamic subtest timelines: SUCCESS (4.031s)
[109.094751] Starting dynamic subtest: requests
[111.877252] Dynamic subtest requests: SUCCESS (2.793s)
[111.886643] Starting dynamic subtest: objects
[112.797541] Dynamic subtest objects: SUCCESS (0.920s)
[112.808066] Starting dynamic subtest: phys
[113.665427] Dynamic subtest phys: SUCCESS (0.867s)
[113.674877] Starting dynamic subtest: dmabuf
[114.628755] Dynamic subtest dmabuf: SUCCESS (0.962s)
[114.639862] Starting dynamic subtest: vma
[121.563892] Dynamic subtest vma: SUCCESS (6.935s)
[121.572637] Starting dynamic subtest: evict
[123.139279] Dynamic subtest evict: SUCCESS (1.575s)
[123.151917] Starting dynamic subtest: gtt
[126.749871] Dynamic subtest gtt: SUCCESS (3.610s)
[126.759166] Starting dynamic subtest: hugepages
[127.801529] Dynamic subtest hugepages: SUCCESS (1.051s)
[127.812120] Starting dynamic subtest: memory_region
[131.825483] Dynamic subtest memory_region: SUCCESS (4.023s)
[131.827214] Subtest mock: SUCCESS (43.551s)
[131.903078] [FACT i915_selftest (mock)] deleted: kernel.kmod_is_loaded.i915: true
[131.904992] [018/130] (891s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions-varying-size)
[136.244554] Starting subtest: short-flip-before-cursor-atomic-transitions-varying-size
[137.294204] Subtest short-flip-before-cursor-atomic-transitions-varying-size: SUCCESS (1.048s)
[137.590752] [FACT kms_cursor_legacy (short-flip-before-cursor-atomic-transitions-varying-size)] new: kernel.kmod_is_loaded.amdgpu: true
[137.590903] [FACT kms_cursor_legacy (short-flip-before-cursor-atomic-transitions-varying-size)] new: kernel.kmod_is_loaded.i915: true
[137.591808] [FACT kms_cursor_legacy (short-flip-before-cursor-atomic-transitions-varying-size)] new: kernel.kmod_is_loaded.xe: true
[137.594079] [019/130] (885s left) kms_hdr (bpc-switch-dpms)
[137.799112] Starting subtest: bpc-switch-dpms
[141.169716] Starting dynamic subtest: pipe-A-DP-3
[142.383038] Dynamic subtest pipe-A-DP-3: SUCCESS (1.213s)
[142.384222] Subtest bpc-switch-dpms: SUCCESS (4.584s)
[142.752568] [020/130] (880s left) gem_userptr_blits (dmabuf-unsync)
[142.838890] Subtest dmabuf-unsync: SKIP (0.000s)
[142.984064] [021/130] (880s left) gem_ctx_persistence (many-contexts)
[143.060276] Starting subtest: many-contexts
[180.760930] Subtest many-contexts: SUCCESS (37.699s)
[180.901691] [022/130] (842s left) gem_flink_basic (bad-flink)
[180.974100] Starting subtest: bad-flink
[180.975007] Subtest bad-flink: SUCCESS (0.000s)
[181.115368] [023/130] (842s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-onoff)
[181.319558] Starting subtest: psr-1p-primscrn-cur-indfb-onoff
[181.321296] Subtest psr-1p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[181.459613] [024/130] (842s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[181.667538] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[182.305048] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.637s)
[182.554312] [025/130] (841s left) gem_exec_schedule (lateslice)
[182.636535] Starting subtest: lateslice
[182.637497] Starting dynamic subtest: rcs0
[182.680063] Dynamic subtest rcs0: SUCCESS (0.043s)
[182.689532] Starting dynamic subtest: bcs0
[182.698992] Dynamic subtest bcs0: SUCCESS (0.019s)
[182.708559] Starting dynamic subtest: vcs0
[182.719823] Dynamic subtest vcs0: SUCCESS (0.020s)
[182.729225] Starting dynamic subtest: vcs1
[182.738483] Dynamic subtest vcs1: SUCCESS (0.018s)
[182.747853] Starting dynamic subtest: vecs0
[182.758119] Dynamic subtest vecs0: SUCCESS (0.019s)
[182.767544] Starting dynamic subtest: vecs1
[182.778464] Dynamic subtest vecs1: SUCCESS (0.020s)
[182.787873] Starting dynamic subtest: ccs0
[182.797248] Dynamic subtest ccs0: SUCCESS (0.019s)
[182.798272] Subtest lateslice: SUCCESS (0.161s)
[183.186788] [026/130] (840s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-blt)
[183.388609] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-blt
[186.072550] Subtest fbc-1p-primscrn-pri-indfb-draw-blt: SUCCESS (2.685s)
[186.305801] [027/130] (837s left) kms_cursor_crc (cursor-sliding-max-size)
[186.754238] Starting subtest: cursor-sliding-max-size
[186.757275] Subtest cursor-sliding-max-size: SKIP (0.000s)
[186.951442] [028/130] (837s left) gem_exec_fence (syncobj-timeline-multiple-ext-nodes)
[187.628266] Starting subtest: syncobj-timeline-multiple-ext-nodes
[187.632499] Subtest syncobj-timeline-multiple-ext-nodes: SUCCESS (0.005s)
[187.884756] [FACT gem_exec_fence (syncobj-timeline-multiple-ext-nodes)] new: kernel.kmod_is_loaded.vgem: true
[187.887910] [029/130] (836s left) gem_mmap (bad-object)
[187.971387] Subtest bad-object: SKIP (0.000s)
[188.058026] [030/130] (836s left) gem_sync (basic-all)
[188.149950] Starting subtest: basic-all
[190.202358] Subtest basic-all: SUCCESS (2.052s)
[190.412734] [031/130] (833s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-blt)
[190.631619] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-blt
[190.633303] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[190.787226] [032/130] (833s left) gem_reg_read (timestamp-monotonic)
[190.866034] Starting subtest: timestamp-monotonic
[195.298261] Subtest timestamp-monotonic: SUCCESS (4.432s)
[195.384031] [033/130] (828s left) kms_ccs (bad-pixel-format-4-tiled-dg2-rc-ccs)
[195.592474] Starting subtest: bad-pixel-format-4-tiled-dg2-rc-ccs
[195.593365] Starting dynamic subtest: pipe-A-DP-3
[195.601268] Dynamic subtest pipe-A-DP-3: SUCCESS (0.001s)
[195.610850] Starting dynamic subtest: pipe-B-DP-3
[195.618751] Dynamic subtest pipe-B-DP-3: SUCCESS (0.001s)
[195.628058] Starting dynamic subtest: pipe-C-DP-3
[195.635822] Dynamic subtest pipe-C-DP-3: SUCCESS (0.001s)
[195.644664] Starting dynamic subtest: pipe-D-DP-3
[195.653346] Dynamic subtest pipe-D-DP-3: SUCCESS (0.001s)
[195.654241] Subtest bad-pixel-format-4-tiled-dg2-rc-ccs: SUCCESS (0.007s)
[196.043239] [034/130] (828s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-gtt)
[196.256684] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-gtt
[196.258375] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[196.374745] [035/130] (828s left) syncobj_timeline (multi-wait-for-submit-signaled)
[196.453731] Starting subtest: multi-wait-for-submit-signaled
[196.454738] Subtest multi-wait-for-submit-signaled: SUCCESS (0.001s)
[196.564782] [036/130] (827s left) kms_plane_scaling (intel-max-src-size)
[196.777252] Starting subtest: intel-max-src-size
[196.790382] Starting dynamic subtest: pipe-A-DP-3
[196.997645] Dynamic subtest pipe-A-DP-3: SUCCESS (0.206s)
[196.999450] Subtest intel-max-src-size: SUCCESS (0.220s)
[197.430827] [037/130] (827s left) kms_frontbuffer_tracking (fbcpsr-modesetfrombusy)
[197.641805] Starting subtest: fbcpsr-modesetfrombusy
[197.643517] Subtest fbcpsr-modesetfrombusy: SKIP (0.000s)
[197.785799] [038/130] (826s left) gem_lmem_swapping (heavy-verify-multi-ccs)
[200.557350] Starting subtest: heavy-verify-multi-ccs
[200.559449] Starting dynamic subtest: lmem0
[212.014232] Dynamic subtest lmem0: SUCCESS (11.457s)
[212.015213] Subtest heavy-verify-multi-ccs: SUCCESS (11.457s)
[213.576697] [FACT gem_lmem_swapping (heavy-verify-multi-ccs)] deleted: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[213.578030] [FACT gem_lmem_swapping (heavy-verify-multi-ccs)] deleted: kernel.kmod_is_loaded.i915: true
[213.579853] [039/130] (811s left) gem_exec_schedule (preempt)
[215.103728] Starting subtest: preempt
[215.104676] Starting dynamic subtest: rcs0
[215.174569] Dynamic subtest rcs0: SUCCESS (0.071s)
[215.183689] Starting dynamic subtest: bcs0
[215.207345] Dynamic subtest bcs0: SUCCESS (0.032s)
[215.216480] Starting dynamic subtest: vcs0
[215.237643] Dynamic subtest vcs0: SUCCESS (0.030s)
[215.246863] Starting dynamic subtest: vcs1
[215.267755] Dynamic subtest vcs1: SUCCESS (0.030s)
[215.276960] Starting dynamic subtest: vecs0
[215.297255] Dynamic subtest vecs0: SUCCESS (0.029s)
[215.306436] Starting dynamic subtest: vecs1
[215.326032] Dynamic subtest vecs1: SUCCESS (0.028s)
[215.335408] Starting dynamic subtest: ccs0
[215.356825] Dynamic subtest ccs0: SUCCESS (0.030s)
[215.357782] Subtest preempt: SUCCESS (0.254s)
[215.787452] [FACT gem_exec_schedule (preempt)] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[215.790914] [FACT gem_exec_schedule (preempt)] new: kernel.kmod_is_loaded.i915: true
[215.794835] [040/130] (808s left) syncobj_timeline (host-signal-points)
[215.879879] Starting subtest: host-signal-points
[215.885802] Subtest host-signal-points: SUCCESS (0.006s)
[216.011289] [041/130] (808s left) gem_sync (basic-store-all)
[216.106435] Starting subtest: basic-store-all
[220.663395] Subtest basic-store-all: SUCCESS (4.557s)
[220.893578] [042/130] (803s left) gem_mmap_gtt (basic-small-bo)
[220.972493] Subtest basic-small-bo: SKIP (0.000s)
[221.046438] [043/130] (803s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-plflip-blt)
[221.259946] Starting subtest: fbc-1p-primscrn-indfb-plflip-blt
[224.220688] Subtest fbc-1p-primscrn-indfb-plflip-blt: SUCCESS (2.961s)
[224.382675] [044/130] (800s left) gem_exec_params (invalid-ring2)
[224.467673] Starting subtest: invalid-ring2
[224.468570] Subtest invalid-ring2: SUCCESS (0.000s)
[224.625745] [045/130] (800s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[224.838049] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu
[224.839739] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[224.974212] [046/130] (800s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-render)
[225.186988] Starting subtest: psr-1p-offscren-pri-shrfb-draw-render
[225.188856] Subtest psr-1p-offscren-pri-shrfb-draw-render: SKIP (0.000s)
[225.294370] [047/130] (799s left) gem_spin_batch (engines)
[225.385509] Starting subtest: engines
[225.386456] Starting dynamic subtest: rcs0
[228.644868] Dynamic subtest rcs0: SUCCESS (3.256s)
[228.658551] Starting dynamic subtest: bcs0
[231.882974] Dynamic subtest bcs0: SUCCESS (3.236s)
[231.893439] Starting dynamic subtest: vcs0
[235.117061] Dynamic subtest vcs0: SUCCESS (3.232s)
[235.130721] Starting dynamic subtest: vcs1
[238.355198] Dynamic subtest vcs1: SUCCESS (3.236s)
[238.365540] Starting dynamic subtest: vecs0
[241.589793] Dynamic subtest vecs0: SUCCESS (3.232s)
[241.599887] Starting dynamic subtest: vecs1
[244.823410] Dynamic subtest vecs1: SUCCESS (3.232s)
[244.833646] Starting dynamic subtest: ccs0
[248.057388] Dynamic subtest ccs0: SUCCESS (3.232s)
[248.059191] Subtest engines: SUCCESS (22.670s)
[248.321847] [048/130] (776s left) kms_psr (psr-primary-mmap-cpu)
[248.528491] Subtest psr-primary-mmap-cpu: SKIP (0.000s)
[248.593842] [049/130] (776s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-pgflip-blt)
[248.803923] Starting subtest: fbc-1p-primscrn-indfb-pgflip-blt
[251.621672] Subtest fbc-1p-primscrn-indfb-pgflip-blt: SUCCESS (2.819s)
[251.794530] [050/130] (773s left) kms_cursor_legacy (flip-vs-cursor-crc-legacy)
[252.001607] Starting subtest: flip-vs-cursor-crc-legacy
[252.559753] Subtest flip-vs-cursor-crc-legacy: SUCCESS (0.557s)
[252.836315] [051/130] (772s left) syncobj_timeline (signal-point-0)
[252.914927] Starting subtest: signal-point-0
[252.916009] Subtest signal-point-0: SUCCESS (0.000s)
[253.023329] [052/130] (772s left) vgem_basic (bad-flag)
[253.090803] Starting subtest: bad-flag
[253.091888] Subtest bad-flag: SUCCESS (0.000s)
[253.187971] [053/130] (772s left) kms_cursor_crc (cursor-random-512x512)
[253.609132] Starting subtest: cursor-random-512x512
[253.611384] Subtest cursor-random-512x512: SKIP (0.001s)
[253.842691] [054/130] (771s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-signa
[253.922989] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-signaled
[254.025392] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-signaled: SUCCESS (0.101s)
[254.151688] [055/130] (771s left) kms_vblank (wait-busy-hang)
[254.357278] Starting subtest: wait-busy-hang
[254.359340] Starting dynamic subtest: pipe-A-DP-3
[280.616958] Dynamic subtest pipe-A-DP-3: SUCCESS (26.258s)
[280.629324] Starting dynamic subtest: pipe-D-DP-3
[305.547367] Dynamic subtest pipe-D-DP-3: SUCCESS (24.926s)
[305.548273] Subtest wait-busy-hang: SUCCESS (51.189s)
[305.772502] [056/130] (719s left) kms_psr (fbc-psr-cursor-mmap-gtt)
[305.979162] Subtest fbc-psr-cursor-mmap-gtt: SKIP (0.000s)
[306.041441] [057/130] (719s left) kms_fb_coherency (memset-crc)
[306.247997] Starting subtest: memset-crc
[306.248939] Starting dynamic subtest: mmap-offset-fixed
[306.769500] Dynamic subtest mmap-offset-fixed: SUCCESS (0.520s)
[306.867567] Subtest memset-crc: SUCCESS (0.618s)
[307.161557] [058/130] (718s left) drm_fdinfo (isolation)
[307.234182] Starting subtest: isolation
[307.235171] Starting dynamic subtest: rcs0
[307.237084] Dynamic subtest rcs0: SKIP (0.000s)
[307.246854] Starting dynamic subtest: bcs0
[307.249154] Dynamic subtest bcs0: SKIP (0.000s)
[307.258684] Starting dynamic subtest: vcs0
[307.261084] Dynamic subtest vcs0: SKIP (0.000s)
[307.270460] Starting dynamic subtest: vcs1
[307.272435] Dynamic subtest vcs1: SKIP (0.000s)
[307.281692] Starting dynamic subtest: vecs0
[307.283627] Dynamic subtest vecs0: SKIP (0.000s)
[307.295285] Starting dynamic subtest: vecs1
[307.297362] Dynamic subtest vecs1: SKIP (0.000s)
[307.306460] Starting dynamic subtest: ccs0
[307.308402] Dynamic subtest ccs0: SKIP (0.000s)
[307.310435] Subtest isolation: SKIP (0.006s)
[307.350986] [059/130] (718s left) gem_busy (parallel)
[307.624565] Starting subtest: parallel
[307.625505] Starting dynamic subtest: rcs0
[307.756891] Dynamic subtest rcs0: SUCCESS (0.132s)
[307.768521] Starting dynamic subtest: bcs0
[307.814409] Dynamic subtest bcs0: SUCCESS (0.057s)
[307.824472] Starting dynamic subtest: vcs0
[307.872555] Dynamic subtest vcs0: SUCCESS (0.058s)
[307.887898] Starting dynamic subtest: vcs1
[307.938975] Dynamic subtest vcs1: SUCCESS (0.065s)
[307.962483] Starting dynamic subtest: vecs0
[308.012750] Dynamic subtest vecs0: SUCCESS (0.073s)
[308.032849] Starting dynamic subtest: vecs1
[308.086130] Dynamic subtest vecs1: SUCCESS (0.072s)
[308.096105] Starting dynamic subtest: ccs0
[308.141552] Dynamic subtest ccs0: SUCCESS (0.055s)
[308.142582] Subtest parallel: SUCCESS (0.517s)
[308.450503] [060/130] (717s left) gem_ctx_param (invalid-set-ringsize)
[308.532584] Starting subtest: invalid-set-ringsize
[308.533501] Subtest invalid-set-ringsize: SUCCESS (0.000s)
[308.626577] [061/130] (717s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-mmap-wc)
[308.841901] Starting subtest: fbc-1p-offscren-pri-indfb-draw-mmap-wc
[308.843663] Subtest fbc-1p-offscren-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[308.975729] [062/130] (717s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-gtt)
[309.186904] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-gtt
[309.188656] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[309.350577] [063/130] (716s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-upscaling)
[309.556393] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-upscaling
[309.557305] Starting dynamic subtest: pipe-A-valid-mode
[311.884022] Dynamic subtest pipe-A-valid-mode: SUCCESS (2.326s)
[311.885868] Subtest flip-32bpp-4tile-to-64bpp-4tile-upscaling: SUCCESS (2.328s)
[312.171042] [064/130] (714s left) api_intel_bb (full-batch)
[312.248185] Starting subtest: full-batch
[312.271483] Subtest full-batch: SUCCESS (0.023s)
[312.470019] [065/130] (713s left) sw_sync (alloc_timeline)
[312.521160] Starting subtest: alloc_timeline
[312.522103] Subtest alloc_timeline: SUCCESS (0.000s)
[312.596344] [066/130] (713s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0)
[312.810521] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0
[314.026249] Subtest x-tiled-max-hw-stride-64bpp-rotate-0: SUCCESS (1.208s)
[314.187587] [067/130] (712s left) drm_mm (drm_mm)
[314.242638] Starting subtest: drm_mm
[314.259299] Starting dynamic subtest: drm_test_mm_init
[314.374611] Dynamic subtest drm_test_mm_init: SUCCESS (0.112s)
[314.413195] Starting dynamic subtest: drm_test_mm_debug
[314.482188] Dynamic subtest drm_test_mm_debug: SUCCESS (0.107s)
[314.521965] Starting dynamic subtest: drm_test_mm_align32
[314.578108] Dynamic subtest drm_test_mm_align32: SUCCESS (0.091s)
[314.616153] Starting dynamic subtest: drm_test_mm_align64
[314.684813] Dynamic subtest drm_test_mm_align64: SUCCESS (0.105s)
[314.722337] Starting dynamic subtest: drm_test_mm_lowest
[314.786129] Dynamic subtest drm_test_mm_lowest: SUCCESS (0.097s)
[314.820965] Starting dynamic subtest: drm_test_mm_highest
[314.923043] Dynamic subtest drm_test_mm_highest: SUCCESS (0.135s)
[314.924707] Subtest drm_mm: SUCCESS (0.678s)
[315.150820] [068/130] (711s left) kms_flip (2x-blocking-wf_vblank)
[315.361059] Starting subtest: 2x-blocking-wf_vblank
[315.375517] Subtest 2x-blocking-wf_vblank: SKIP (0.014s)
[315.492216] [069/130] (711s left) kms_plane_scaling (planes-downscale-factor-0-5-unity-scaling)
[315.697409] Starting subtest: planes-downscale-factor-0-5-unity-scaling
[315.698280] Starting dynamic subtest: pipe-A
[315.800907] Dynamic subtest pipe-A: SUCCESS (0.103s)
[315.814900] Starting dynamic subtest: pipe-B
[316.037064] Dynamic subtest pipe-B: SUCCESS (0.235s)
[316.050660] Starting dynamic subtest: pipe-C
[316.257565] Dynamic subtest pipe-C: SUCCESS (0.220s)
[316.274882] Starting dynamic subtest: pipe-D
[316.479541] Dynamic subtest pipe-D: SUCCESS (0.220s)
[316.480485] Subtest planes-downscale-factor-0-5-unity-scaling: SUCCESS (0.782s)
[316.795197] [070/130] (709s left) gem_exec_fence (expired-history)
[317.264995] Starting subtest: expired-history
[323.993103] Subtest expired-history: SUCCESS (6.728s)
[325.589494] [071/130] (701s left) gem_exec_schedule (u-semaphore-resolve)
[325.683502] Starting subtest: u-semaphore-resolve
[325.752555] Subtest u-semaphore-resolve: SUCCESS (0.069s)
[326.199055] [072/130] (700s left) perf_pmu (enable-race)
[326.271601] Starting subtest: enable-race
[326.272516] Starting dynamic subtest: rcs0
[339.048559] Dynamic subtest rcs0: SUCCESS (12.776s)
[339.058276] Starting dynamic subtest: bcs0
[351.801614] Dynamic subtest bcs0: SUCCESS (12.752s)
[351.811795] Starting dynamic subtest: vcs0
[364.552675] Dynamic subtest vcs0: SUCCESS (12.750s)
[364.563106] Starting dynamic subtest: vcs1
[377.303597] Dynamic subtest vcs1: SUCCESS (12.750s)
[377.313820] Starting dynamic subtest: vecs0
[390.057719] Dynamic subtest vecs0: SUCCESS (12.753s)
[390.067674] Starting dynamic subtest: vecs1
[402.807547] Dynamic subtest vecs1: SUCCESS (12.749s)
[402.817387] Starting dynamic subtest: ccs0
[415.556173] Dynamic subtest ccs0: SUCCESS (12.748s)
[415.557141] Subtest enable-race: SUCCESS (89.283s)
[415.729289] [073/130] (611s left) sw_sync (sync_expired_merge)
[415.781657] Starting subtest: sync_expired_merge
[416.855514] Subtest sync_expired_merge: SUCCESS (1.074s)
[416.932608] [074/130] (610s left) kms_flip (2x-modeset-vs-vblank-race)
[417.145082] Starting subtest: 2x-modeset-vs-vblank-race
[417.161526] Subtest 2x-modeset-vs-vblank-race: SKIP (0.016s)
[417.282999] [075/130] (609s left) kms_cursor_crc (cursor-offscreen-64x64)
[417.596979] Starting subtest: cursor-offscreen-64x64
[417.601931] Starting dynamic subtest: pipe-A-DP-3
[420.081354] Dynamic subtest pipe-A-DP-3: SUCCESS (2.479s)
[420.130490] Starting dynamic subtest: pipe-D-DP-3
[422.596163] Dynamic subtest pipe-D-DP-3: SUCCESS (2.474s)
[422.597149] Subtest cursor-offscreen-64x64: SUCCESS (4.998s)
[423.048160] [076/130] (604s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[423.269402] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[424.261064] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SUCCESS (0.991s)
[424.509781] [077/130] (602s left) kms_joiner (basic-max-non-joiner)
[424.717648] Starting subtest: basic-max-non-joiner
[424.719401] Subtest basic-max-non-joiner: SKIP (0.000s)
[424.812965] [078/130] (602s left) kms_getfb (getfb-handle-valid)
[424.890436] Starting subtest: getfb-handle-valid
[424.891436] Subtest getfb-handle-valid: SUCCESS (0.000s)
[425.021186] [079/130] (602s left) kms_color (invalid-ctm-matrix-sizes)
[425.224431] Starting subtest: invalid-ctm-matrix-sizes
[425.225431] Starting dynamic subtest: pipe-A
[425.227641] Dynamic subtest pipe-A: SUCCESS (0.003s)
[425.237557] Starting dynamic subtest: pipe-B
[425.238467] Dynamic subtest pipe-B: SUCCESS (0.005s)
[425.248292] Starting dynamic subtest: pipe-C
[425.249384] Dynamic subtest pipe-C: SUCCESS (0.005s)
[425.259080] Starting dynamic subtest: pipe-D
[425.259972] Dynamic subtest pipe-D: SUCCESS (0.006s)
[425.260921] Subtest invalid-ctm-matrix-sizes: SUCCESS (0.021s)
[425.345940] [080/130] (601s left) gem_wait (await)
[425.438656] Starting subtest: await
[425.439567] Starting dynamic subtest: all-engines
[426.570539] Dynamic subtest all-engines: SUCCESS (1.131s)
[426.583033] Starting dynamic subtest: rcs0
[427.634399] Dynamic subtest rcs0: SUCCESS (1.063s)
[427.646916] Starting dynamic subtest: bcs0
[428.695785] Dynamic subtest bcs0: SUCCESS (1.060s)
[428.713809] Starting dynamic subtest: vcs0
[429.759688] Dynamic subtest vcs0: SUCCESS (1.061s)
[429.778165] Starting dynamic subtest: vcs1
[430.824868] Dynamic subtest vcs1: SUCCESS (1.062s)
[430.867527] Starting dynamic subtest: vecs0
[431.898884] Dynamic subtest vecs0: SUCCESS (1.071s)
[431.913221] Starting dynamic subtest: vecs1
[432.959672] Dynamic subtest vecs1: SUCCESS (1.058s)
[432.977975] Starting dynamic subtest: ccs0
[434.021770] Dynamic subtest ccs0: SUCCESS (1.059s)
[434.023721] Subtest await: SUCCESS (8.584s)
[434.325983] [081/130] (593s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-pgflip-blt)
[434.541999] Starting subtest: fbcpsr-2p-primscrn-indfb-pgflip-blt
[434.543794] Subtest fbcpsr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[434.686384] [082/130] (592s left) syncobj_timeline (invalid-signal-illegal-point)
[434.764790] Starting subtest: invalid-signal-illegal-point
[434.765657] Subtest invalid-signal-illegal-point: SUCCESS (0.000s)
[434.924458] [083/130] (592s left) gem_partial_pwrite_pread (writes-after-reads-snoop)
[435.008312] Subtest writes-after-reads-snoop: SKIP (0.000s)
[435.087586] [084/130] (592s left) gem_mmap_wc (write-cpu-read-wc)
[435.165405] Subtest write-cpu-read-wc: SKIP (0.000s)
[435.242058] [085/130] (592s left) kms_chamelium_hpd (hdmi-hpd-storm-disable)
[435.521176] Subtest hdmi-hpd-storm-disable: SKIP (0.000s)
[435.675725] [086/130] (592s left) kms_ccs (crc-primary-suspend-4-tiled-lnl-ccs)
[435.884471] Starting subtest: crc-primary-suspend-4-tiled-lnl-ccs
[435.886287] Subtest crc-primary-suspend-4-tiled-lnl-ccs: SKIP (0.000s)
[436.180432] [087/130] (591s left) prime_mmap (test_map_unmap)
[436.258934] Starting subtest: test_map_unmap
[436.259911] Starting dynamic subtest: test_map_unmap-smem
[436.307591] Dynamic subtest test_map_unmap-smem: SUCCESS (0.048s)
[436.317698] Starting dynamic subtest: test_map_unmap-lmem0
[436.515063] Dynamic subtest test_map_unmap-lmem0: SUCCESS (0.206s)
[436.516198] Subtest test_map_unmap: SUCCESS (0.256s)
[436.690950] [088/130] (591s left) kms_cursor_legacy (flip-vs-cursor-legacy)
[436.896748] Starting subtest: flip-vs-cursor-legacy
[446.375044] Subtest flip-vs-cursor-legacy: SUCCESS (9.478s)
[446.647182] [089/130] (581s left) kms_atomic_transition (plane-all-modeset-transition-fencing-internal-panels)
[446.856567] Starting subtest: plane-all-modeset-transition-fencing-internal-panels
[446.858307] Subtest plane-all-modeset-transition-fencing-internal-panels: SKIP (0.000s)
[446.944414] [090/130] (581s left) kms_flip (dpms-off-confusion-interruptible)
[447.163301] Starting subtest: dpms-off-confusion-interruptible
[447.167813] Starting dynamic subtest: A-DP3
[454.970169] Dynamic subtest A-DP3: SUCCESS (7.802s)
[454.989767] Starting dynamic subtest: B-DP3
[462.719067] Dynamic subtest B-DP3: SUCCESS (7.747s)
[462.730445] Starting dynamic subtest: C-DP3
[470.452663] Dynamic subtest C-DP3: SUCCESS (7.732s)
[470.489452] Starting dynamic subtest: D-DP3
[478.216108] Dynamic subtest D-DP3: SUCCESS (7.760s)
[478.218411] Subtest dpms-off-confusion-interruptible: SUCCESS (31.054s)
[478.591972] [091/130] (549s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-msflip-blt)
[478.801928] Starting subtest: psr-2p-primscrn-shrfb-msflip-blt
[478.803633] Subtest psr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[478.946854] [092/130] (549s left) gem_pxp (create-regular-context-2)
[481.548966] Starting subtest: create-regular-context-2
[481.551584] Subtest create-regular-context-2: SKIP (0.000s)
[481.655044] [093/130] (546s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-upscaling)
[481.861502] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-upscaling
[481.863654] Starting dynamic subtest: pipe-A-valid-mode
[481.947984] Dynamic subtest pipe-A-valid-mode: SKIP (0.084s)
[481.949886] Subtest flip-64bpp-ytile-to-32bpp-ytile-upscaling: SKIP (0.086s)
[482.164876] [094/130] (546s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[482.380478] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[482.382180] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[482.514545] [095/130] (545s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-mc-ccs)
[482.724489] Starting subtest: missing-ccs-buffer-4-tiled-mtl-mc-ccs
[482.726576] Starting dynamic subtest: pipe-A-DP-3
[482.735144] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[482.745009] Starting dynamic subtest: pipe-B-DP-3
[482.753755] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[482.763395] Starting dynamic subtest: pipe-C-DP-3
[482.771843] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[482.781643] Starting dynamic subtest: pipe-D-DP-3
[482.792503] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[482.794268] Subtest missing-ccs-buffer-4-tiled-mtl-mc-ccs: SKIP (0.006s)
[483.168579] [096/130] (545s left) kms_cursor_legacy (torture-move)
[483.375783] Starting subtest: torture-move
[483.376660] Starting dynamic subtest: pipe-A
[488.830802] Dynamic subtest pipe-A: SUCCESS (5.452s)
[488.840946] Starting dynamic subtest: pipe-B
[494.273394] Dynamic subtest pipe-B: SUCCESS (5.441s)
[494.283758] Starting dynamic subtest: pipe-C
[499.833169] Dynamic subtest pipe-C: SUCCESS (5.560s)
[499.843192] Starting dynamic subtest: pipe-D
[505.444738] Dynamic subtest pipe-D: SUCCESS (5.611s)
[505.455384] Starting dynamic subtest: all-pipes
[510.980756] Dynamic subtest all-pipes: SUCCESS (5.535s)
[510.981651] Subtest torture-move: SUCCESS (27.604s)
[511.074384] [097/130] (517s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[511.303974] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[511.305748] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[511.442039] [098/130] (517s left) kms_frontbuffer_tracking (psr-rgb565-draw-pwrite)
[511.658044] Starting subtest: psr-rgb565-draw-pwrite
[511.659879] Subtest psr-rgb565-draw-pwrite: SKIP (0.000s)
[511.786405] [099/130] (516s left) gem_render_copy (mixed-tiled-to-yf-tiled-ccs)
[511.873622] Starting subtest: mixed-tiled-to-yf-tiled-ccs
[511.875844] Subtest mixed-tiled-to-yf-tiled-ccs: SKIP (0.000s)
[512.132185] [100/130] (516s left) kms_chamelium_frames (dp-frame-dump)
[512.429839] Subtest dp-frame-dump: SKIP (0.000s)
[512.591036] [101/130] (516s left) kms_psr (fbc-pr-suspend)
[512.800144] Subtest fbc-pr-suspend: SKIP (0.000s)
[512.859737] [102/130] (515s left) kms_lease (lease-again)
[513.069489] Starting subtest: lease-again
[513.071660] Starting dynamic subtest: pipe-A-DP-3
[513.073928] Dynamic subtest pipe-A-DP-3: SUCCESS (0.003s)
[513.085431] Starting dynamic subtest: pipe-B-DP-3
[513.086333] Dynamic subtest pipe-B-DP-3: SUCCESS (0.003s)
[513.096390] Starting dynamic subtest: pipe-C-DP-3
[513.097327] Dynamic subtest pipe-C-DP-3: SUCCESS (0.003s)
[513.107210] Starting dynamic subtest: pipe-D-DP-3
[513.108143] Dynamic subtest pipe-D-DP-3: SUCCESS (0.003s)
[513.109065] Subtest lease-again: SUCCESS (0.018s)
[513.236758] [103/130] (515s left) syncobj_wait (multi-wait-submitted-signaled)
[513.314944] Starting subtest: multi-wait-submitted-signaled
[513.315872] Subtest multi-wait-submitted-signaled: SUCCESS (0.000s)
[513.484884] [104/130] (515s left) gem_softpin (noreloc-s3)
[513.569556] Starting subtest: noreloc-S3
[534.082957] Subtest noreloc-S3: SUCCESS (5.424s)
[534.219601] [105/130] (509s left) sw_sync (sync_multi_producer_single_consumer)
[534.272073] Starting subtest: sync_multi_producer_single_consumer
[535.347756] Subtest sync_multi_producer_single_consumer: SUCCESS (1.075s)
[535.429583] [106/130] (508s left) i915_query (query-garbage)
[535.507249] Starting subtest: query-garbage
[535.508134] Subtest query-garbage: SUCCESS (0.000s)
[535.666385] [107/130] (508s left) kms_prop_blob (blob-multiple)
[535.745200] Starting subtest: blob-multiple
[535.775268] Subtest blob-multiple: SUCCESS (0.029s)
[535.960977] [108/130] (508s left) kms_big_fb (y-tiled-64bpp-rotate-180)
[536.182341] Starting subtest: y-tiled-64bpp-rotate-180
[536.184096] Subtest y-tiled-64bpp-rotate-180: SKIP (0.000s)
[536.287125] [109/130] (508s left) gem_exec_whisper (basic-fds-all)
[536.379119] Starting subtest: basic-fds-all
[558.503001] Subtest basic-fds-all: SUCCESS (22.122s)
[558.757779] [110/130] (485s left) gen9_exec_parse (bb-start-out)
[558.840070] Subtest bb-start-out: SKIP (0.000s)
[558.928617] [111/130] (485s left) kms_atomic (atomic-invalid-params)
[559.138358] Starting subtest: atomic-invalid-params
[559.140459] Starting dynamic subtest: pipe-A-DP-3
[559.699483] Dynamic subtest pipe-A-DP-3: SUCCESS (0.559s)
[559.700902] Subtest atomic-invalid-params: SUCCESS (0.561s)
[559.978706] [112/130] (484s left) kms_chamelium_color (ctm-negative)
[560.187032] Subtest ctm-negative: SKIP (0.000s)
[560.239966] [113/130] (484s left) kms_getfb (getfb-handle-closed)
[560.321238] Starting subtest: getfb-handle-closed
[560.322175] Subtest getfb-handle-closed: SUCCESS (0.000s)
[560.510971] [114/130] (484s left) kms_pm_dc (dc6-dpms)
[561.726148] Starting subtest: dc6-dpms
[561.728019] Subtest dc6-dpms: SKIP (0.000s)
[561.815546] [115/130] (483s left) kms_psr2_su (page_flip-xrgb8888)
[561.894127] Subtest page_flip-XRGB8888: SKIP (0.000s)
[561.976103] [116/130] (482s left) kms_pipe_crc_basic (read-crc)
[562.183458] Starting subtest: read-crc
[562.185791] Starting dynamic subtest: pipe-A-DP-3
[562.853574] Dynamic subtest pipe-A-DP-3: SUCCESS (0.666s)
[562.881872] Starting dynamic subtest: pipe-B-DP-3
[563.445664] Dynamic subtest pipe-B-DP-3: SUCCESS (0.587s)
[563.465910] Starting dynamic subtest: pipe-C-DP-3
[563.996451] Dynamic subtest pipe-C-DP-3: SUCCESS (0.548s)
[564.020694] Starting dynamic subtest: pipe-D-DP-3
[564.558105] Dynamic subtest pipe-D-DP-3: SUCCESS (0.558s)
[564.559235] Subtest read-crc: SUCCESS (2.375s)
[564.847729] [117/130] (480s left) kms_ccs (crc-primary-rotation-180-4-tiled-lnl-ccs)
[565.060528] Starting subtest: crc-primary-rotation-180-4-tiled-lnl-ccs
[565.062458] Subtest crc-primary-rotation-180-4-tiled-lnl-ccs: SKIP (0.000s)
[565.387373] [118/130] (479s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-sf)
[565.604847] Subtest pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.000s)
[565.658795] [119/130] (479s left) sriov_basic (bind-unbind-vf)
[565.740033] Subtest bind-unbind-vf: SKIP (0.000s)
[565.809070] [120/130] (479s left) gem_exec_reloc (basic-write-read-noreloc)
[565.895130] Subtest basic-write-read-noreloc: SKIP (0.000s)
[565.983721] [121/130] (479s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-fully-sf)
[566.200756] Subtest fbc-pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[566.260741] [122/130] (478s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs-cc)
[566.471361] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs-cc
[566.473509] Starting dynamic subtest: pipe-A-DP-3
[566.482144] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[566.493788] Starting dynamic subtest: pipe-B-DP-3
[566.502373] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[566.513831] Starting dynamic subtest: pipe-C-DP-3
[566.522291] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[566.533920] Starting dynamic subtest: pipe-D-DP-3
[566.544888] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[566.547042] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs-cc: SKIP (0.006s)
[566.926839] [123/130] (478s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-blt)
[567.138188] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-blt
[567.139983] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[567.256571] [124/130] (478s left) perf (non-zero-reason)
[567.607000] Starting subtest: non-zero-reason
[567.607881] Starting dynamic subtest: 0-rcs0
[570.682768] Dynamic subtest 0-rcs0: FAIL (2.744s)
[570.684465] Subtest non-zero-reason: FAIL (2.745s)
[570.757414] [125/130] (474s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-pwrite)
[570.973852] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-pwrite
[570.975649] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[571.122816] [126/130] (474s left) i915_pm_rps (thresholds-idle)
[571.209271] Starting subtest: thresholds-idle
[571.211269] Subtest thresholds-idle: SKIP (0.001s)
[571.363783] [127/130] (474s left) kms_sequence (queue-busy)
[571.577516] Starting subtest: queue-busy
[571.580062] Starting dynamic subtest: pipe-A-DP-3
[574.132405] Dynamic subtest pipe-A-DP-3: SUCCESS (2.553s)
[574.146495] Starting dynamic subtest: pipe-B-DP-3
[576.602667] Dynamic subtest pipe-B-DP-3: SUCCESS (2.468s)
[576.620716] Starting dynamic subtest: pipe-C-DP-3
[579.087409] Dynamic subtest pipe-C-DP-3: SUCCESS (2.481s)
[579.101555] Starting dynamic subtest: pipe-D-DP-3
[581.570226] Dynamic subtest pipe-D-DP-3: SUCCESS (2.480s)
[581.571230] Subtest queue-busy: SUCCESS (9.990s)
[581.845724] [128/130] (463s left) gem_eio (hibernate)
[582.133647] Starting subtest: hibernate
[597.743538] Subtest hibernate: SUCCESS (15.609s)
[597.947006] Aborting: Lockdep not active

/proc/lockdep_stats contents:
 lock-classes:                         2318 [max: 8192]
 direct dependencies:                 27076 [max: 524288]
 indirect dependencies:              240473
 all direct dependencies:            575644
 dependency chains:                   42995 [max: 524288]
 dependency chain hlocks used:       195663 [max: 2621440]
 dependency chain hlocks lost:            0
 in-hardirq chains:                     415
 in-softirq chains:                     981
 in-process chains:                   41599
 stack-trace entries:                353693 [max: 524288]
 number of stack traces:              15861
 number of stack hash chains:         10128
 combined max dependencies:      4109197312
 hardirq-safe locks:                    121
 hardirq-unsafe locks:                 1368
 softirq-safe locks:                    263
 softirq-unsafe locks:                 1261
 irq-safe locks:                        280
 irq-unsafe locks:                     1368
 hardirq-read-safe locks:                 6
 hardirq-read-unsafe locks:             415
 softirq-read-safe locks:                10
 softirq-read-unsafe locks:             410
 irq-read-safe locks:                    10
 irq-read-unsafe locks:                 415
 uncategorized locks:                   394
 unused locks:                            1
 max locking depth:                      18
 max bfs queue depth:                   423
 max lock class index:                 2317
 debug_locks:                             0

 zapped classes:                        824
 zapped lock chains:                  16131
 large chain blocks:                      1

[597.974681] Closing watchdogs
results: parsing output: 0/ for test: kms_psr
results: parsing output: 1/ for test: kms_selftest
results: parsing output: 2/ for test: gem_exec_balancer
results: parsing output: 3/ for test: kms_addfb_basic
results: parsing output: 4/ for test: gem_mmap_gtt
results: parsing output: 5/ for test: kms_rotation_crc
results: parsing output: 6/ for test: gem_mmap_wc
results: parsing output: 7/ for test: gem_render_linear_blits
results: parsing output: 8/ for test: kms_big_fb
results: parsing output: 9/ for test: api_intel_bb
results: parsing output: 10/ for test: perf
results: parsing output: 11/ for test: gem_create
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: gem_exec_params
results: parsing output: 14/ for test: kms_plane_scaling
results: parsing output: 15/ for test: kms_atomic_transition
results: parsing output: 16/ for test: i915_selftest
results: parsing output: 17/ for test: kms_cursor_legacy
results: parsing output: 18/ for test: kms_hdr
results: parsing output: 19/ for test: gem_userptr_blits
results: parsing output: 20/ for test: gem_ctx_persistence
results: parsing output: 21/ for test: gem_flink_basic
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: kms_big_fb
results: parsing output: 24/ for test: gem_exec_schedule
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_cursor_crc
results: parsing output: 27/ for test: gem_exec_fence
results: parsing output: 28/ for test: gem_mmap
results: parsing output: 29/ for test: gem_sync
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: gem_reg_read
results: parsing output: 32/ for test: kms_ccs
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: syncobj_timeline
results: parsing output: 35/ for test: kms_plane_scaling
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: gem_lmem_swapping
results: parsing output: 38/ for test: gem_exec_schedule
results: parsing output: 39/ for test: syncobj_timeline
results: parsing output: 40/ for test: gem_sync
results: parsing output: 41/ for test: gem_mmap_gtt
results: parsing output: 42/ for test: kms_frontbuffer_tracking
results: parsing output: 43/ for test: gem_exec_params
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: gem_spin_batch
results: parsing output: 47/ for test: kms_psr
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: kms_cursor_legacy
results: parsing output: 50/ for test: syncobj_timeline
results: parsing output: 51/ for test: vgem_basic
results: parsing output: 52/ for test: kms_cursor_crc
results: parsing output: 53/ for test: syncobj_timeline
results: parsing output: 54/ for test: kms_vblank
results: parsing output: 55/ for test: kms_psr
results: parsing output: 56/ for test: kms_fb_coherency
results: parsing output: 57/ for test: drm_fdinfo
results: parsing output: 58/ for test: gem_busy
results: parsing output: 59/ for test: gem_ctx_param
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: kms_flip_scaled_crc
results: parsing output: 63/ for test: api_intel_bb
results: parsing output: 64/ for test: sw_sync
results: parsing output: 65/ for test: kms_big_fb
results: parsing output: 66/ for test: drm_mm
results: parsing output: 67/ for test: kms_flip
results: parsing output: 68/ for test: kms_plane_scaling
results: parsing output: 69/ for test: gem_exec_fence
results: parsing output: 70/ for test: gem_exec_schedule
results: parsing output: 71/ for test: perf_pmu
results: parsing output: 72/ for test: sw_sync
results: parsing output: 73/ for test: kms_flip
results: parsing output: 74/ for test: kms_cursor_crc
results: parsing output: 75/ for test: kms_big_fb
results: parsing output: 76/ for test: kms_joiner
results: parsing output: 77/ for test: kms_getfb
results: parsing output: 78/ for test: kms_color
results: parsing output: 79/ for test: gem_wait
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: syncobj_timeline
results: parsing output: 82/ for test: gem_partial_pwrite_pread
results: parsing output: 83/ for test: gem_mmap_wc
results: parsing output: 84/ for test: kms_chamelium_hpd
results: parsing output: 85/ for test: kms_ccs
results: parsing output: 86/ for test: prime_mmap
results: parsing output: 87/ for test: kms_cursor_legacy
results: parsing output: 88/ for test: kms_atomic_transition
results: parsing output: 89/ for test: kms_flip
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: gem_pxp
results: parsing output: 92/ for test: kms_flip_scaled_crc
results: parsing output: 93/ for test: kms_big_fb
results: parsing output: 94/ for test: kms_ccs
results: parsing output: 95/ for test: kms_cursor_legacy
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: gem_render_copy
results: parsing output: 99/ for test: kms_chamelium_frames
results: parsing output: 100/ for test: kms_psr
results: parsing output: 101/ for test: kms_lease
results: parsing output: 102/ for test: syncobj_wait
results: parsing output: 103/ for test: gem_softpin
results: parsing output: 104/ for test: sw_sync
results: parsing output: 105/ for test: i915_query
results: parsing output: 106/ for test: kms_prop_blob
results: parsing output: 107/ for test: kms_big_fb
results: parsing output: 108/ for test: gem_exec_whisper
results: parsing output: 109/ for test: gen9_exec_parse
results: parsing output: 110/ for test: kms_atomic
results: parsing output: 111/ for test: kms_chamelium_color
results: parsing output: 112/ for test: kms_getfb
results: parsing output: 113/ for test: kms_pm_dc
results: parsing output: 114/ for test: kms_psr2_su
results: parsing output: 115/ for test: kms_pipe_crc_basic
results: parsing output: 116/ for test: kms_ccs
results: parsing output: 117/ for test: kms_psr2_sf
results: parsing output: 118/ for test: sriov_basic
results: parsing output: 119/ for test: gem_exec_reloc
results: parsing output: 120/ for test: kms_psr2_sf
results: parsing output: 121/ for test: kms_ccs
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: perf
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: i915_pm_rps
results: parsing output: 126/ for test: kms_sequence
results: parsing output: 127/ for test: gem_eio
results: parsing output: 128/ for test: gem_render_copy
results: no output, setting notrun
results: parsing output: 129/ for test: i915_pm_freq_api
results: no output, setting notrun
Done.