[67.356343] Initializing watchdogs
[67.356507]   /dev/watchdog0
[67.385221] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:03:00.0: 8086:56a0 Intel Dg2 (Gen12) DG2 [Arc A770]
[67.402370] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[67.407874] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[67.410529] [001/132] (960s left) gem_exec_schedule (preempt-hang)
[67.865808] Starting subtest: preempt-hang
[67.866801] Starting dynamic subtest: rcs0
[68.212778] Dynamic subtest rcs0: SUCCESS (0.350s)
[68.220584] Starting dynamic subtest: bcs0
[68.530247] Dynamic subtest bcs0: SUCCESS (0.319s)
[68.538488] Starting dynamic subtest: vcs0
[68.877129] Dynamic subtest vcs0: SUCCESS (0.349s)
[68.885217] Starting dynamic subtest: vcs1
[69.206867] Dynamic subtest vcs1: SUCCESS (0.332s)
[69.214803] Starting dynamic subtest: vecs0
[69.532337] Dynamic subtest vecs0: SUCCESS (0.327s)
[69.540495] Starting dynamic subtest: vecs1
[69.865356] Dynamic subtest vecs1: SUCCESS (0.335s)
[69.873933] Starting dynamic subtest: ccs0
[70.205419] Dynamic subtest ccs0: SUCCESS (0.341s)
[70.206269] Subtest preempt-hang: SUCCESS (2.355s)
[70.489091] [002/132] (957s left) gem_mmap_offset (open-flood)
[70.556188] Starting subtest: open-flood
[92.002129] Subtest open-flood: SUCCESS (21.484s)
[92.131078] [003/132] (935s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[92.309967] Starting subtest: basic-flip-before-cursor-varying-size
[93.421878] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.111s)
[93.679443] [004/132] (934s left) gem_exec_reloc (basic-wc-cpu-noreloc)
[93.759248] Subtest basic-wc-cpu-noreloc: SKIP (0.000s)
[93.831163] [005/132] (934s left) kms_chamelium_color (ctm-0-50)
[94.013465] Subtest ctm-0-50: SKIP (0.000s)
[94.065495] [006/132] (934s left) kms_ccs (crc-primary-rotation-180-yf-tiled-ccs)
[94.243701] Starting subtest: crc-primary-rotation-180-yf-tiled-ccs
[94.244571] Starting dynamic subtest: pipe-A-DP-3
[94.252845] Dynamic subtest pipe-A-DP-3: SKIP (0.001s)
[94.261248] Starting dynamic subtest: pipe-B-DP-3
[94.269643] Dynamic subtest pipe-B-DP-3: SKIP (0.001s)
[94.277792] Starting dynamic subtest: pipe-C-DP-3
[94.286121] Dynamic subtest pipe-C-DP-3: SKIP (0.001s)
[94.294110] Starting dynamic subtest: pipe-D-DP-3
[94.302839] Dynamic subtest pipe-D-DP-3: SKIP (0.001s)
[94.305146] Subtest crc-primary-rotation-180-yf-tiled-ccs: SKIP (0.006s)
[94.700519] [007/132] (933s left) gem_partial_pwrite_pread (reads)
[94.776433] Subtest reads: SKIP (0.000s)
[94.862753] [008/132] (933s left) perf (unprivileged-single-ctx-counters)
[95.221398] Starting subtest: unprivileged-single-ctx-counters
[95.223118] Subtest unprivileged-single-ctx-counters: SKIP (0.000s)
[95.281434] [009/132] (933s left) kms_universal_plane (universal-plane-pageflip-windowed)
[95.457960] Starting subtest: universal-plane-pageflip-windowed
[95.459936] Starting dynamic subtest: pipe-A-DP-3
[96.064480] Dynamic subtest pipe-A-DP-3: SUCCESS (0.605s)
[96.078355] Starting dynamic subtest: pipe-B-DP-3
[96.565986] Dynamic subtest pipe-B-DP-3: SUCCESS (0.499s)
[96.575641] Starting dynamic subtest: pipe-C-DP-3
[97.049540] Dynamic subtest pipe-C-DP-3: SUCCESS (0.481s)
[97.070110] Starting dynamic subtest: pipe-D-DP-3
[97.568541] Dynamic subtest pipe-D-DP-3: SUCCESS (0.516s)
[97.569444] Subtest universal-plane-pageflip-windowed: SUCCESS (2.111s)
[97.827303] [010/132] (930s left) sw_sync (sync_multi_producer_single_consumer)
[97.872624] Starting subtest: sync_multi_producer_single_consumer
[98.957224] Subtest sync_multi_producer_single_consumer: SUCCESS (1.075s)
[99.023212] [011/132] (929s left) kms_chamelium_hpd (hdmi-hpd-with-enabled-mode)
[99.290509] Subtest hdmi-hpd-with-enabled-mode: SKIP (0.000s)
[99.436744] [012/132] (929s left) gem_render_copy (y-tiled)
[99.513562] Starting subtest: y-tiled
[99.515619] Subtest y-tiled: SKIP (0.000s)
[99.761065] [013/132] (928s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[99.939149] Starting subtest: compare-crc-sanitycheck-xr24
[99.940056] Starting dynamic subtest: pipe-A-DP-3
[100.507602] Dynamic subtest pipe-A-DP-3: SUCCESS (0.568s)
[100.519010] Starting dynamic subtest: pipe-B-DP-3
[100.979903] Dynamic subtest pipe-B-DP-3: SUCCESS (0.471s)
[100.990484] Starting dynamic subtest: pipe-C-DP-3
[101.440405] Dynamic subtest pipe-C-DP-3: SUCCESS (0.458s)
[101.449966] Starting dynamic subtest: pipe-D-DP-3
[101.902374] Dynamic subtest pipe-D-DP-3: SUCCESS (0.460s)
[101.903357] Subtest compare-crc-sanitycheck-xr24: SUCCESS (1.963s)
[102.134584] [014/132] (926s left) kms_psr (psr2-primary-mmap-gtt)
[102.315621] Subtest psr2-primary-mmap-gtt: SKIP (0.000s)
[102.369695] [015/132] (926s left) perf (non-zero-reason)
[102.581219] Starting subtest: non-zero-reason
[102.582158] Starting dynamic subtest: 0-rcs0
[105.654148] Dynamic subtest 0-rcs0: FAIL (2.748s)
[105.655906] Subtest non-zero-reason: FAIL (2.748s)
[105.736447] [016/132] (923s left) gem_eio (wait-immediate)
[105.998250] Starting subtest: wait-immediate
[106.606964] Subtest wait-immediate: SUCCESS (0.609s)
[106.822058] [017/132] (922s left) kms_dirtyfb (default-dirtyfb-ioctl)
[107.001057] Starting subtest: default-dirtyfb-ioctl
[107.002005] Starting dynamic subtest: A-DP-3
[108.439784] Dynamic subtest A-DP-3: SUCCESS (1.438s)
[108.440665] Subtest default-dirtyfb-ioctl: SUCCESS (1.439s)
[108.704658] [018/132] (920s left) gem_pxp (reject-modify-context-protection-off-3)
[111.302273] Starting subtest: reject-modify-context-protection-off-3
[111.304530] Subtest reject-modify-context-protection-off-3: SKIP (0.000s)
[111.454861] [019/132] (917s left) gen7_exec_parse (basic-rejected)
[111.531279] Subtest basic-rejected: SKIP (0.000s)
[111.595489] [020/132] (917s left) kms_frontbuffer_tracking (psr-suspend)
[111.780818] Starting subtest: psr-suspend
[111.782499] Subtest psr-suspend: SKIP (0.000s)
[111.985718] [021/132] (917s left) kms_psr (pr-cursor-plane-onoff)
[112.166560] Subtest pr-cursor-plane-onoff: SKIP (0.000s)
[112.229835] [022/132] (916s left) syncobj_timeline (invalid-wait-bad-flags)
[112.303122] Starting subtest: invalid-wait-bad-flags
[112.304013] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[112.430900] [023/132] (916s left) sysfs_timeslice_duration (timeout)
[112.507200] Starting subtest: timeout
[112.508164] Starting dynamic subtest: vecs0
[114.287290] Dynamic subtest vecs0: SUCCESS (1.780s)
[114.297159] Starting dynamic subtest: ccs0
[116.031131] Dynamic subtest ccs0: SUCCESS (1.743s)
[116.040294] Starting dynamic subtest: vcs0
[117.679053] Dynamic subtest vcs0: SUCCESS (1.647s)
[117.688231] Starting dynamic subtest: bcs0
[118.737102] Dynamic subtest bcs0: SUCCESS (1.057s)
[118.746370] Starting dynamic subtest: vecs1
[120.459229] Dynamic subtest vecs1: SUCCESS (1.721s)
[120.468503] Starting dynamic subtest: rcs0
[122.142145] Dynamic subtest rcs0: SUCCESS (1.682s)
[122.151346] Starting dynamic subtest: vcs1
[123.855254] Dynamic subtest vcs1: SUCCESS (1.712s)
[123.856259] Subtest timeout: SUCCESS (11.349s)
[123.985918] [024/132] (905s left) kms_async_flips (async-flip-hang)
[124.169530] Starting subtest: async-flip-hang
[124.392524] Starting dynamic subtest: pipe-A-DP-3
[130.981416] Dynamic subtest pipe-A-DP-3: SUCCESS (6.588s)
[131.204944] Starting dynamic subtest: pipe-B-DP-3
[137.965409] Dynamic subtest pipe-B-DP-3: SUCCESS (6.768s)
[138.141123] Starting dynamic subtest: pipe-C-DP-3
[143.988532] Dynamic subtest pipe-C-DP-3: SUCCESS (5.849s)
[144.167468] Starting dynamic subtest: pipe-D-DP-3
[150.001532] Dynamic subtest pipe-D-DP-3: SUCCESS (5.843s)
[150.002412] Subtest async-flip-hang: SUCCESS (25.831s)
[150.444726] [025/132] (878s left) kms_cursor_crc (cursor-onscreen-512x512)
[150.896798] Starting subtest: cursor-onscreen-512x512
[150.899924] Subtest cursor-onscreen-512x512: SKIP (0.001s)
[151.213653] [026/132] (878s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-cc-modifier)
[151.395313] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-cc-modifier
[151.397054] Subtest pixel-format-y-tiled-gen12-rc-ccs-cc-modifier: SKIP (0.000s)
[151.503386] [027/132] (878s left) gem_exec_schedule (implicit-boths)
[151.584222] Starting subtest: implicit-boths
[151.585170] Starting dynamic subtest: rcs0
[151.872787] Dynamic subtest rcs0: SUCCESS (0.288s)
[151.882118] Starting dynamic subtest: bcs0
[152.078339] Dynamic subtest bcs0: SUCCESS (0.205s)
[152.087545] Starting dynamic subtest: vcs0
[152.296349] Dynamic subtest vcs0: SUCCESS (0.217s)
[152.305521] Starting dynamic subtest: vcs1
[152.515287] Dynamic subtest vcs1: SUCCESS (0.218s)
[152.524342] Starting dynamic subtest: vecs0
[152.731893] Dynamic subtest vecs0: SUCCESS (0.216s)
[152.741360] Starting dynamic subtest: vecs1
[152.965488] Dynamic subtest vecs1: SUCCESS (0.233s)
[152.975370] Starting dynamic subtest: ccs0
[153.198168] Dynamic subtest ccs0: SUCCESS (0.232s)
[153.198962] Subtest implicit-boths: SUCCESS (1.614s)
[153.586912] [028/132] (875s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[153.766302] Starting subtest: cursorB-vs-flipA-toggle
[153.769179] Subtest cursorB-vs-flipA-toggle: SKIP (0.002s)
[153.865206] [029/132] (875s left) gem_softpin (allocator-evict)
[153.944479] Starting subtest: allocator-evict
[153.945410] Starting dynamic subtest: rcs0
[180.936468] Dynamic subtest rcs0: SUCCESS (26.992s)
[180.946347] Starting dynamic subtest: bcs0
[208.265721] Dynamic subtest bcs0: SUCCESS (27.328s)
[208.275693] Starting dynamic subtest: vcs0
[235.274328] Dynamic subtest vcs0: SUCCESS (27.007s)
[235.284970] Starting dynamic subtest: vcs1
[262.499668] Dynamic subtest vcs1: SUCCESS (27.224s)
[262.509504] Starting dynamic subtest: vecs0
[289.485481] Dynamic subtest vecs0: SUCCESS (26.984s)
[289.495416] Starting dynamic subtest: vecs1
[316.472483] Dynamic subtest vecs1: SUCCESS (26.985s)
[316.482448] Starting dynamic subtest: ccs0
[343.369542] Dynamic subtest ccs0: SUCCESS (26.895s)
[343.370482] Subtest allocator-evict: SUCCESS (189.423s)
[343.566795] [030/132] (686s left) gem_exec_fence (nb-await)
[343.806515] Starting subtest: nb-await
[343.807451] Starting dynamic subtest: rcs0
[343.902600] Dynamic subtest rcs0: SUCCESS (0.096s)
[343.912583] Starting dynamic subtest: bcs0
[343.964389] Dynamic subtest bcs0: SUCCESS (0.061s)
[343.974297] Starting dynamic subtest: vcs0
[344.025508] Dynamic subtest vcs0: SUCCESS (0.060s)
[344.035455] Starting dynamic subtest: vcs1
[344.086540] Dynamic subtest vcs1: SUCCESS (0.060s)
[344.096447] Starting dynamic subtest: vecs0
[344.147331] Dynamic subtest vecs0: SUCCESS (0.060s)
[344.157302] Starting dynamic subtest: vecs1
[344.208759] Dynamic subtest vecs1: SUCCESS (0.060s)
[344.218591] Starting dynamic subtest: ccs0
[344.271176] Dynamic subtest ccs0: SUCCESS (0.061s)
[344.272242] Subtest nb-await: SUCCESS (0.465s)
[345.047651] [FACT gem_exec_fence (nb-await)] new: kernel.kmod_is_loaded.vgem: true
[345.049768] [031/132] (684s left) kms_rotation_crc (primary-4-tiled-reflect-x-180)
[345.243955] Starting subtest: primary-4-tiled-reflect-x-180
[355.573334] Subtest primary-4-tiled-reflect-x-180: SUCCESS (10.328s)
[355.950270] [032/132] (673s left) gem_ctx_param (invalid-get-engines)
[356.030615] Starting subtest: invalid-get-engines
[356.032830] Subtest invalid-get-engines: SUCCESS (0.002s)
[356.181075] [033/132] (673s left) kms_cursor_legacy (cursora-vs-flipb-toggle)
[356.370359] Starting subtest: cursorA-vs-flipB-toggle
[356.372158] Subtest cursorA-vs-flipB-toggle: SKIP (0.001s)
[356.521644] [034/132] (673s left) gem_tiled_partial_pwrite_pread (writes-after-reads)
[356.608858] Subtest writes-after-reads: SKIP (0.000s)
[356.675213] [035/132] (673s left) gem_exec_balancer (individual)
[356.766680] Starting subtest: individual
[358.930965] Subtest individual: SUCCESS (2.164s)
[359.384914] [036/132] (670s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-blt)
[359.580935] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-blt
[359.582713] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[359.723695] [037/132] (670s left) gem_render_copy (yf-tiled-ccs-to-y-tiled)
[359.812543] Starting subtest: yf-tiled-ccs-to-y-tiled
[359.814506] Subtest yf-tiled-ccs-to-y-tiled: SKIP (0.000s)
[360.065879] [038/132] (670s left) syncobj_timeline (etime-single-wait-submitted)
[360.146555] Starting subtest: etime-single-wait-submitted
[360.255826] Subtest etime-single-wait-submitted: SUCCESS (0.108s)
[360.352227] [039/132] (670s left) kms_plane_scaling (plane-upscale-20x20-with-pixel-format)
[360.541549] Starting subtest: plane-upscale-20x20-with-pixel-format
[360.542377] Starting dynamic subtest: pipe-A
[362.164113] Dynamic subtest pipe-A: SUCCESS (1.622s)
[362.177561] Starting dynamic subtest: pipe-B
[363.861147] Dynamic subtest pipe-B: SUCCESS (1.696s)
[363.887579] Starting dynamic subtest: pipe-C
[364.173867] Dynamic subtest pipe-C: SUCCESS (0.311s)
[364.196872] Starting dynamic subtest: pipe-D
[364.487966] Dynamic subtest pipe-D: SUCCESS (0.312s)
[364.489035] Subtest plane-upscale-20x20-with-pixel-format: SUCCESS (3.947s)
[364.803863] [040/132] (665s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-pwrite)
[364.996220] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-pwrite
[364.997948] Subtest psr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[365.204944] [041/132] (665s left) i915_query (hwconfig_table)
[365.286500] Starting subtest: hwconfig_table
[365.464102] Subtest hwconfig_table: SUCCESS (0.001s)
[365.546875] [042/132] (665s left) kms_ccs (ccs-on-another-bo-y-tiled-ccs)
[365.736440] Starting subtest: ccs-on-another-bo-y-tiled-ccs
[365.737353] Starting dynamic subtest: pipe-A-DP-3
[365.745972] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[365.757034] Starting dynamic subtest: pipe-B-DP-3
[365.765652] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[365.776484] Starting dynamic subtest: pipe-C-DP-3
[365.784735] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[365.795504] Starting dynamic subtest: pipe-D-DP-3
[365.805769] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[365.807891] Subtest ccs-on-another-bo-y-tiled-ccs: SKIP (0.007s)
[366.197949] [043/132] (664s left) kms_psr (fbc-psr2-primary-mmap-cpu)
[366.383434] Subtest fbc-psr2-primary-mmap-cpu: SKIP (0.000s)
[366.442795] [044/132] (664s left) kms_big_fb (x-tiled-8bpp-rotate-270)
[366.650785] Starting subtest: x-tiled-8bpp-rotate-270
[366.713378] Subtest x-tiled-8bpp-rotate-270: SKIP (0.062s)
[366.898256] [045/132] (663s left) i915_drm_fdinfo (virtual-busy-idle-all)
[366.971498] Starting subtest: virtual-busy-idle-all
[366.973334] Subtest virtual-busy-idle-all: SKIP (0.000s)
[367.063342] [046/132] (663s left) kms_big_fb (4-tiled-64bpp-rotate-180)
[367.259417] Starting subtest: 4-tiled-64bpp-rotate-180
[369.297063] Subtest 4-tiled-64bpp-rotate-180: SUCCESS (2.036s)
[369.567740] [047/132] (661s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-msflip-blt)
[369.762853] Starting subtest: psr-1p-primscrn-shrfb-msflip-blt
[369.764473] Subtest psr-1p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[369.910132] [048/132] (661s left) gem_bad_reloc (negative-reloc-lut)
[369.997432] Subtest negative-reloc-lut: SKIP (0.000s)
[370.080672] [049/132] (661s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-sf)
[370.271586] Subtest pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.000s)
[370.326056] [050/132] (660s left) gem_wait (invalid-flags)
[370.413735] Starting subtest: invalid-flags
[370.414595] Subtest invalid-flags: SUCCESS (0.000s)
[370.710388] [051/132] (660s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite)
[370.902983] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite
[370.904694] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[371.033806] [052/132] (660s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling)
[371.229760] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling
[371.231460] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling: SKIP (0.000s)
[371.341666] [053/132] (660s left) perf_pmu (render-node-busy-idle)
[371.448802] Starting subtest: render-node-busy-idle
[371.449666] Starting dynamic subtest: rcs0
[372.162742] Dynamic subtest rcs0: SUCCESS (0.712s)
[372.182193] Starting dynamic subtest: bcs0
[372.828646] Dynamic subtest bcs0: SUCCESS (0.660s)
[372.848150] Starting dynamic subtest: vcs0
[373.493955] Dynamic subtest vcs0: SUCCESS (0.660s)
[373.513481] Starting dynamic subtest: vcs1
[374.160701] Dynamic subtest vcs1: SUCCESS (0.661s)
[374.181223] Starting dynamic subtest: vecs0
[374.826639] Dynamic subtest vecs0: SUCCESS (0.660s)
[374.846384] Starting dynamic subtest: vecs1
[375.495698] Dynamic subtest vecs1: SUCCESS (0.664s)
[375.515305] Starting dynamic subtest: ccs0
[376.159650] Dynamic subtest ccs0: SUCCESS (0.659s)
[376.162593] Subtest render-node-busy-idle: SUCCESS (4.713s)
[376.270887] [054/132] (655s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-msflip-blt)
[376.463197] Starting subtest: fbc-2p-scndscrn-indfb-msflip-blt
[376.464968] Subtest fbc-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[376.610803] [055/132] (654s left) kms_ccs (bad-rotation-90-4-tiled-mtl-mc-ccs)
[376.799268] Starting subtest: bad-rotation-90-4-tiled-mtl-mc-ccs
[376.800175] Starting dynamic subtest: pipe-A-DP-3
[376.808315] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[376.819378] Starting dynamic subtest: pipe-B-DP-3
[376.827731] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[376.838550] Starting dynamic subtest: pipe-C-DP-3
[376.846782] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[376.857874] Starting dynamic subtest: pipe-D-DP-3
[376.869136] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[376.871517] Subtest bad-rotation-90-4-tiled-mtl-mc-ccs: SKIP (0.007s)
[377.280717] [056/132] (654s left) kms_cursor_crc (cursor-random-32x32)
[377.565332] Starting subtest: cursor-random-32x32
[377.616514] Subtest cursor-random-32x32: SKIP (0.050s)
[378.061911] [057/132] (653s left) api_intel_allocator (two-level-inception)
[378.139474] Starting subtest: two-level-inception
[383.559104] Subtest two-level-inception: SUCCESS (5.419s)
[383.724555] [058/132] (648s left) perf (short-reads)
[383.964945] Starting subtest: short-reads
[383.986365] Subtest short-reads: SUCCESS (0.022s)
[384.043384] [059/132] (647s left) kms_cursor_legacy (2x-long-cursor-vs-flip-legacy)
[384.229992] Starting subtest: 2x-long-cursor-vs-flip-legacy
[384.233161] Subtest 2x-long-cursor-vs-flip-legacy: SKIP (0.002s)
[384.335072] [060/132] (647s left) gem_exec_balancer (sequential)
[384.425446] Starting subtest: sequential
[390.445250] Subtest sequential: SUCCESS (6.020s)
[390.880369] [061/132] (641s left) kms_vblank (ts-continuation-modeset)
[391.071349] Starting subtest: ts-continuation-modeset
[391.072242] Starting dynamic subtest: pipe-A-DP-3
[391.601132] Dynamic subtest pipe-A-DP-3: SUCCESS (0.529s)
[391.615737] Starting dynamic subtest: pipe-D-DP-3
[392.039247] Dynamic subtest pipe-D-DP-3: SUCCESS (0.434s)
[392.040293] Subtest ts-continuation-modeset: SUCCESS (0.969s)
[392.280934] [062/132] (639s left) gem_exec_schedule (u-semaphore-resolve)
[392.373453] Starting subtest: u-semaphore-resolve
[392.456442] Subtest u-semaphore-resolve: SUCCESS (0.083s)
[392.884639] [063/132] (639s left) gem_ctx_engines (none)
[392.975531] Starting subtest: none
[392.978629] Subtest none: SUCCESS (0.003s)
[393.233139] [064/132] (639s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-move)
[393.430173] Starting subtest: fbc-2p-primscrn-cur-indfb-move
[393.431936] Subtest fbc-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[393.573998] [065/132] (638s left) i915_pm_rpm (reg-read-ioctl)
[396.098146] Starting subtest: reg-read-ioctl
[396.906047] Subtest reg-read-ioctl: SUCCESS (0.809s)
[397.299425] [066/132] (635s left) core_getstats (basic)
[397.383550] Starting subtest: basic
[397.384445] Subtest basic: SUCCESS (0.000s)
[397.531943] [067/132] (634s left) sw_sync (sync_busy)
[397.581981] Starting subtest: sync_busy
[397.583764] Subtest sync_busy: SUCCESS (0.003s)
[397.659244] [068/132] (634s left) kms_atomic_transition (modeset-transition-nonblocking)
[397.845433] Starting subtest: modeset-transition-nonblocking
[397.846342] Starting dynamic subtest: 1x-outputs
[400.466706] Dynamic subtest 1x-outputs: SUCCESS (2.620s)
[400.471759] Subtest modeset-transition-nonblocking: SUCCESS (2.623s)
[400.715858] [069/132] (631s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[400.905393] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt
[400.907115] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[401.028662] [070/132] (631s left) gem_mmap_gtt (medium-copy)
[401.116206] Subtest medium-copy: SKIP (0.000s)
[401.195468] [071/132] (631s left) gem_mmap_gtt (cpuset-medium-copy-odd)
[401.275271] Subtest cpuset-medium-copy-odd: SKIP (0.000s)
[401.341250] [072/132] (631s left) gem_exec_reloc (basic-gtt-active)
[401.427627] Subtest basic-gtt-active: SKIP (0.000s)
[401.521560] [073/132] (631s left) gem_workarounds (reset-context)
[401.619870] Starting subtest: reset-context
[401.883247] Subtest reset-context: SUCCESS (0.263s)
[402.101106] [074/132] (630s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-blt)
[402.292424] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-blt
[408.947340] Subtest fbc-1p-offscreen-pri-indfb-draw-blt: SUCCESS (6.655s)
[409.150475] [075/132] (623s left) prime_vgem (basic-blt)
[409.250373] Starting subtest: basic-blt
[409.324940] Subtest basic-blt: SUCCESS (0.076s)
[409.513204] [076/132] (623s left) kms_prime (basic-modeset-hybrid)
[409.707018] Subtest basic-modeset-hybrid: SKIP (0.000s)
[409.885957] [077/132] (623s left) kms_flip (2x-flip-vs-dpms-on-nop-interruptible)
[410.077546] Starting subtest: 2x-flip-vs-dpms-on-nop-interruptible
[410.093286] Subtest 2x-flip-vs-dpms-on-nop-interruptible: SKIP (0.015s)
[410.210162] [078/132] (623s left) gen3_render_mixed_blits
[410.378537] [079/132] (622s left) gem_exec_flush (basic-wb-ro-default)
[410.465437] Subtest basic-wb-ro-default: SKIP (0.000s)
[410.541379] [080/132] (622s left) syncobj_timeline (wait-all-delayed-signal)
[410.624534] Starting subtest: wait-all-delayed-signal
[410.726667] Subtest wait-all-delayed-signal: SUCCESS (0.102s)
[410.898282] [081/132] (622s left) gem_ctx_shared (disjoint-timelines)
[410.991316] Starting subtest: disjoint-timelines
[411.033507] Subtest disjoint-timelines: SUCCESS (0.042s)
[411.334524] [082/132] (622s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-signaled)
[411.417428] Starting subtest: multi-wait-for-submit-available-unsubmitted-signaled
[411.418334] Subtest multi-wait-for-submit-available-unsubmitted-signaled: SUCCESS (0.000s)
[411.565648] [083/132] (622s left) vgem_basic (dmabuf-fence)
[411.630256] Starting subtest: dmabuf-fence
[411.631188] Subtest dmabuf-fence: SUCCESS (0.001s)
[411.782809] [084/132] (621s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-pwrite)
[411.976161] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-pwrite
[415.246353] Subtest fbc-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (3.270s)
[415.432486] [085/132] (618s left) kms_psr (fbc-psr-sprite-blt)
[415.619419] Subtest fbc-psr-sprite-blt: SKIP (0.000s)
[415.675721] [086/132] (618s left) kms_plane (pixel-format-y-tiled-modifier-source-clamping)
[415.867729] Starting subtest: pixel-format-y-tiled-modifier-source-clamping
[415.869421] Subtest pixel-format-y-tiled-modifier-source-clamping: SKIP (0.000s)
[415.969596] [087/132] (617s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-msflip-blt)
[416.166249] Starting subtest: fbcpsr-1p-primscrn-shrfb-msflip-blt
[416.167948] Subtest fbcpsr-1p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[416.340039] [088/132] (617s left) kms_flip (2x-flip-vs-absolute-wf_vblank)
[416.528976] Starting subtest: 2x-flip-vs-absolute-wf_vblank
[416.544125] Subtest 2x-flip-vs-absolute-wf_vblank: SKIP (0.014s)
[416.655506] [089/132] (617s left) kms_big_fb (yf-tiled-8bpp-rotate-270)
[416.856234] Starting subtest: yf-tiled-8bpp-rotate-270
[416.858160] Subtest yf-tiled-8bpp-rotate-270: SKIP (0.000s)
[417.019283] [090/132] (617s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[417.211253] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt
[417.213009] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[417.372869] [091/132] (616s left) gen9_exec_parse (unaligned-jump)
[417.465508] Subtest unaligned-jump: SKIP (0.000s)
[417.541755] [092/132] (616s left) gem_mmap_wc (pf-nonblock)
[417.627377] Subtest pf-nonblock: SKIP (0.000s)
[417.693357] [093/132] (616s left) prime_vgem (coherency-gtt)
[417.798944] Starting subtest: coherency-gtt
[417.801136] Subtest coherency-gtt: SKIP (0.000s)
[417.961370] [094/132] (616s left) gem_render_copy_redux (interruptible)
[418.048993] Starting subtest: interruptible
[418.815031] Subtest interruptible: SUCCESS (0.766s)
[419.023912] [095/132] (615s left) gem_wait (busy)
[419.120297] Starting subtest: busy
[419.121286] Starting dynamic subtest: all-engines
[419.699680] Dynamic subtest all-engines: SUCCESS (0.579s)
[419.712994] Starting dynamic subtest: rcs0
[420.240243] Dynamic subtest rcs0: SUCCESS (0.539s)
[420.252880] Starting dynamic subtest: bcs0
[420.779285] Dynamic subtest bcs0: SUCCESS (0.538s)
[420.792164] Starting dynamic subtest: vcs0
[421.319044] Dynamic subtest vcs0: SUCCESS (0.539s)
[421.331981] Starting dynamic subtest: vcs1
[421.859069] Dynamic subtest vcs1: SUCCESS (0.539s)
[421.871937] Starting dynamic subtest: vecs0
[422.399057] Dynamic subtest vecs0: SUCCESS (0.539s)
[422.410739] Starting dynamic subtest: vecs1
[422.939061] Dynamic subtest vecs1: SUCCESS (0.539s)
[422.950757] Starting dynamic subtest: ccs0
[423.478054] Dynamic subtest ccs0: SUCCESS (0.538s)
[423.478999] Subtest busy: SUCCESS (4.358s)
[423.799721] [096/132] (610s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-wc)
[423.990904] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-wc
[423.992520] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[424.220507] [097/132] (610s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-blt)
[424.415143] Starting subtest: psr-1p-primscrn-cur-indfb-draw-blt
[424.416897] Subtest psr-1p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[424.557946] [098/132] (610s left) kms_frontbuffer_tracking (pipe-fbc-rte)
[424.752964] Starting subtest: pipe-fbc-rte
[424.757968] Subtest pipe-fbc-rte: SKIP (0.000s)
[424.882387] [099/132] (609s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[425.082549] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[426.228485] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (1.146s)
[426.467141] [100/132] (608s left) kms_big_fb (linear-8bpp-rotate-180)
[426.665928] Starting subtest: linear-8bpp-rotate-180
[428.380546] Subtest linear-8bpp-rotate-180: SUCCESS (1.713s)
[428.501468] [101/132] (606s left) gem_ctx_persistence (hang)
[428.590661] Starting subtest: hang
[428.592627] Subtest hang: SKIP (0.000s)
[428.801302] [102/132] (606s left) kms_psr2_sf (fbc-pr-overlay-plane-update-sf-dmg-area)
[428.992543] Subtest fbc-pr-overlay-plane-update-sf-dmg-area: SKIP (0.000s)
[429.059546] [103/132] (606s left) kms_plane_multiple (2x-tiling-yf)
[429.248528] Starting subtest: 2x-tiling-yf
[429.250259] Subtest 2x-tiling-yf: SKIP (0.000s)
[429.358950] [104/132] (605s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[429.552851] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc
[429.554564] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[429.743775] [105/132] (605s left) kms_cursor_edge_walk (64x64-right-edge)
[429.932425] Starting subtest: 64x64-right-edge
[429.933290] Starting dynamic subtest: pipe-A-DP-3
[433.429291] Dynamic subtest pipe-A-DP-3: SUCCESS (3.496s)
[433.448581] Starting dynamic subtest: pipe-D-DP-3
[436.762059] Dynamic subtest pipe-D-DP-3: SUCCESS (3.330s)
[436.763032] Subtest 64x64-right-edge: SUCCESS (6.831s)
[437.021448] [106/132] (598s left) kms_legacy_colorkey (invalid-plane)
[437.210583] Starting subtest: invalid-plane
[437.211482] Starting dynamic subtest: zero-id
[437.212416] Dynamic subtest zero-id: SUCCESS (0.000s)
[437.224224] Starting dynamic subtest: outrange-id
[437.225297] Dynamic subtest outrange-id: SUCCESS (0.000s)
[437.226497] Subtest invalid-plane: SUCCESS (0.002s)
[437.378808] [107/132] (598s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-blt)
[437.572192] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-blt
[437.573901] Subtest fbc-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[437.713227] [108/132] (597s left) kms_feature_discovery (psr1)
[437.907531] Starting subtest: psr1
[437.909247] Subtest psr1: SKIP (0.000s)
[438.089701] [109/132] (597s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted)
[438.176516] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted
[438.279487] Subtest etime-multi-wait-all-for-submit-unsubmitted: SUCCESS (0.102s)
[438.419838] [110/132] (597s left) kms_getfb (getfb-handle-protection)
[438.627544] Starting subtest: getfb-handle-protection
[438.650510] Subtest getfb-handle-protection: SUCCESS (0.023s)
[438.810511] [111/132] (597s left) gem_exec_suspend (basic-s0)
[438.902500] Starting subtest: basic-S0
[438.903434] Starting dynamic subtest: smem
[460.830285] Dynamic subtest smem: SUCCESS (7.114s)
[460.844804] Starting dynamic subtest: lmem0