[57.106461] Initializing watchdogs
[57.106509]   /dev/watchdog0
[57.118370] [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]
[57.122138] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.124358] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.127499] [001/130] (960s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-plflip-blt)
[57.283289] Starting subtest: fbc-2p-primscrn-indfb-plflip-blt
[57.287286] Subtest fbc-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[57.383054] [002/130] (960s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-blt)
[57.488838] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-blt
[60.224072] Subtest fbc-1p-primscrn-spr-indfb-draw-blt: SUCCESS (2.740s)
[60.384066] [003/130] (957s left) kms_flip (flip-vs-suspend)
[60.481455] Starting subtest: flip-vs-suspend
[60.483506] Starting dynamic subtest: A-eDP1
[109.532159] Dynamic subtest A-eDP1: SUCCESS (4.405s)
[109.539486] Starting dynamic subtest: B-eDP1
[158.533304] Dynamic subtest B-eDP1: SUCCESS (4.238s)
[158.544808] Starting dynamic subtest: C-eDP1
[191.555435] Dynamic subtest C-eDP1: SUCCESS (4.406s)
[191.563752] Starting dynamic subtest: D-eDP1
[240.548631] Dynamic subtest D-eDP1: SUCCESS (4.331s)
[240.551147] Subtest flip-vs-suspend: SUCCESS (17.386s)
[241.670461] [004/130] (938s left) kms_content_protection (atomic)
[241.848489] Starting subtest: atomic
[245.112327] Subtest atomic: SKIP (3.259s)
[246.276885] [005/130] (934s left) gem_ctx_shared (detached-shared-gtt)
[246.340628] Starting subtest: detached-shared-gtt
[248.548167] Subtest detached-shared-gtt: SUCCESS (2.152s)
[248.847475] [006/130] (931s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-wc)
[248.952303] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-wc
[248.956280] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[249.064272] [007/130] (931s left) kms_psr (pr-cursor-plane-move)
[249.151079] Starting subtest: pr-cursor-plane-move
[249.155451] Subtest pr-cursor-plane-move: SKIP (0.000s)
[249.221146] [008/130] (931s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-pwrite)
[249.314782] Starting subtest: psr-2p-primscrn-cur-indfb-draw-pwrite
[249.319075] Subtest psr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[249.414960] [009/130] (931s left) kms_big_fb (yf-tiled-addfb)
[249.520012] Starting subtest: yf-tiled-addfb
[249.524539] Subtest yf-tiled-addfb: SKIP (0.000s)
[249.607510] [010/130] (930s left) api_intel_bb (blit-noreloc-keep-cache)
[249.655868] Starting subtest: blit-noreloc-keep-cache
[249.666128] Subtest blit-noreloc-keep-cache: SUCCESS (0.011s)
[249.784180] [011/130] (930s left) gem_ctx_engines (execute-one)
[249.837749] Starting subtest: execute-one
[251.812342] Subtest execute-one: SUCCESS (1.973s)
[252.663683] [012/130] (927s left) kms_cursor_legacy (nonblocking-modeset-vs-cursor-atomic)
[252.786918] Starting subtest: nonblocking-modeset-vs-cursor-atomic
[254.135927] Subtest nonblocking-modeset-vs-cursor-atomic: SUCCESS (1.347s)
[254.933406] [013/130] (925s left) gem_flink_race (flink_name)
[254.966433] Starting subtest: flink_name
[260.422247] Subtest flink_name: SUCCESS (5.399s)
[260.492044] [014/130] (920s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-blt)
[260.614659] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-blt
[260.619210] Subtest fbc-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[260.709281] [015/130] (920s left) gem_softpin (evict-single-offset)
[260.765571] Starting subtest: evict-single-offset
[289.631875] Subtest evict-single-offset: SUCCESS (28.808s)
[289.690040] [016/130] (891s left) i915_pm_rps (thresholds-idle-park)
[289.792499] Starting subtest: thresholds-idle-park
[289.797226] Subtest thresholds-idle-park: SKIP (0.001s)
[289.867668] [017/130] (890s left) kms_addfb_basic (unused-offsets)
[289.924225] Starting subtest: unused-offsets
[289.926719] Subtest unused-offsets: SUCCESS (0.000s)
[290.059146] [018/130] (890s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[290.174208] Starting subtest: fbcpsr-slowdraw
[313.188313] Subtest fbcpsr-slowdraw: SUCCESS (23.011s)
[313.327719] [019/130] (867s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted-signaled)
[313.393304] Starting subtest: multi-wait-all-for-submit-available-submitted-signaled
[313.395653] Subtest multi-wait-all-for-submit-available-submitted-signaled: SUCCESS (0.001s)
[313.502640] [020/130] (867s left) kms_pm_dc (dc9-dpms)
[314.614220] Starting subtest: dc9-dpms
[316.011547] Subtest dc9-dpms: SUCCESS (1.399s)
[316.073775] [021/130] (864s left) kms_frontbuffer_tracking (fbcpsr-1p-rte)
[316.169913] Starting subtest: fbcpsr-1p-rte
[321.022085] Subtest fbcpsr-1p-rte: SUCCESS (4.855s)
[321.166845] [022/130] (859s left) gen9_exec_parse (unaligned-access)
[321.237794] Subtest unaligned-access: SKIP (0.000s)
[321.313072] [023/130] (859s left) gem_userptr_blits (readonly-unsync)
[321.377015] Subtest readonly-unsync: SKIP (0.000s)
[321.494511] [024/130] (859s left) kms_concurrent (multi-plane-atomic-lowres)
[321.602822] Starting subtest: multi-plane-atomic-lowres
[321.604667] Starting dynamic subtest: pipe-A-eDP-1
[322.110548] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.509s)
[322.113070] Subtest multi-plane-atomic-lowres: SUCCESS (0.510s)
[322.907394] [025/130] (858s left) kms_frontbuffer_tracking (psr-2p-rte)
[323.023199] Starting subtest: psr-2p-rte
[323.027525] Subtest psr-2p-rte: SKIP (0.000s)
[323.126386] [026/130] (858s left) gem_mmap_gtt (basic-small-copy-odd)
[323.184925] Subtest basic-small-copy-odd: SKIP (0.000s)
[323.253702] [027/130] (857s left) syncobj_timeline (single-wait-all-for-submit-signaled)
[323.316964] Starting subtest: single-wait-all-for-submit-signaled
[323.319109] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[323.407073] [028/130] (857s left) gem_partial_pwrite_pread (write-display)
[323.463578] Subtest write-display: SKIP (0.000s)
[323.516923] [029/130] (857s left) perf_pmu (faulting-read)
[323.559656] Starting subtest: faulting-read
[323.562052] Starting dynamic subtest: wb
[323.564382] Dynamic subtest wb: SUCCESS (0.001s)
[323.571819] Starting dynamic subtest: wc
[323.573781] Dynamic subtest wc: SUCCESS (0.000s)
[323.581095] Starting dynamic subtest: uc
[323.582894] Dynamic subtest uc: SUCCESS (0.000s)
[323.584712] Subtest faulting-read: SUCCESS (0.002s)
[323.671303] [030/130] (857s left) gem_exec_schedule (implicit-write-read)
[323.743038] Starting subtest: implicit-write-read
[323.745107] Starting dynamic subtest: rcs0
[323.842871] Dynamic subtest rcs0: SUCCESS (0.110s)
[323.852175] Starting dynamic subtest: vcs0
[323.939257] Dynamic subtest vcs0: SUCCESS (0.096s)
[323.949975] Starting dynamic subtest: vcs1
[324.016865] Dynamic subtest vcs1: SUCCESS (0.076s)
[324.028045] Starting dynamic subtest: vecs0
[324.088582] Dynamic subtest vecs0: SUCCESS (0.072s)
[324.103856] Starting dynamic subtest: ccs0
[324.166651] Dynamic subtest ccs0: SUCCESS (0.077s)
[324.168940] Subtest implicit-write-read: SUCCESS (0.433s)
[325.705558] [031/130] (855s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-mc-ccs)
[325.809085] Starting subtest: crc-primary-suspend-4-tiled-dg2-mc-ccs
[325.811380] Starting dynamic subtest: pipe-A-eDP-1
[325.820639] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[325.830452] Starting dynamic subtest: pipe-B-eDP-1
[325.839714] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[325.847715] Starting dynamic subtest: pipe-C-eDP-1
[325.856639] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[325.864386] Starting dynamic subtest: pipe-D-eDP-1
[325.873256] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[325.877710] Subtest crc-primary-suspend-4-tiled-dg2-mc-ccs: SKIP (0.003s)
[327.951812] [032/130] (853s left) kms_cursor_crc (cursor-sliding-64x21)
[328.127833] Starting subtest: cursor-sliding-64x21
[328.170327] Subtest cursor-sliding-64x21: SKIP (0.037s)
[328.409685] [033/130] (852s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-downscaling)
[328.517989] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-downscaling
[328.520289] Starting dynamic subtest: pipe-A-default-mode
[328.808807] Dynamic subtest pipe-A-default-mode: SKIP (0.284s)
[328.813333] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-downscaling: SKIP (0.285s)
[329.586117] [034/130] (851s left) gem_exec_balancer (sequential)
[329.650498] Starting subtest: sequential
[332.587868] Subtest sequential: SUCCESS (2.936s)
[332.919916] [035/130] (848s left) gem_exec_params (secure-non-master)
[332.984740] Starting subtest: secure-non-master
[332.989478] Subtest secure-non-master: SKIP (0.000s)
[333.089822] [036/130] (848s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-render)
[333.189725] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-render
[333.194070] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[333.288083] [037/130] (848s left) syncobj_wait (single-wait-for-submit-unsubmitted)
[333.342157] Starting subtest: single-wait-for-submit-unsubmitted
[333.447890] Subtest single-wait-for-submit-unsubmitted: SUCCESS (0.106s)
[333.519102] [038/130] (848s left) gem_ccs (block-copy-uncompressed)
[333.577543] Starting subtest: block-copy-uncompressed
[333.580049] Starting dynamic subtest: linear-uncompressed-compfmt0-smem-smem
[333.599930] Dynamic subtest linear-uncompressed-compfmt0-smem-smem: SUCCESS (0.022s)
[333.610442] Starting dynamic subtest: xmajor-uncompressed-compfmt0-smem-smem
[333.617602] Dynamic subtest xmajor-uncompressed-compfmt0-smem-smem: SUCCESS (0.017s)
[333.627224] Starting dynamic subtest: tile4-uncompressed-compfmt0-smem-smem
[333.635133] Dynamic subtest tile4-uncompressed-compfmt0-smem-smem: SUCCESS (0.017s)
[333.642871] Starting dynamic subtest: tile64-uncompressed-compfmt0-smem-smem
[333.652634] Dynamic subtest tile64-uncompressed-compfmt0-smem-smem: SUCCESS (0.017s)
[333.655134] Subtest block-copy-uncompressed: SUCCESS (0.075s)
[333.794230] [039/130] (847s left) kms_prop_blob (blob-prop-validate)
[333.850066] Starting subtest: blob-prop-validate
[333.852278] Subtest blob-prop-validate: SUCCESS (0.000s)
[333.959935] [040/130] (847s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs-cc)
[334.054996] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs-cc
[334.057246] Starting dynamic subtest: pipe-A-eDP-1
[334.077438] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[334.086469] Starting dynamic subtest: pipe-B-eDP-1
[334.106238] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[334.114160] Starting dynamic subtest: pipe-C-eDP-1
[334.134077] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[334.142114] Starting dynamic subtest: pipe-D-eDP-1
[334.161822] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[334.164142] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.004s)
[336.238317] [041/130] (845s left) gem_mmap_gtt (fault-concurrent-x)
[336.296133] Subtest fault-concurrent-X: SKIP (0.000s)
[336.348932] [042/130] (845s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-render)
[336.450597] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-render
[336.455113] Subtest psr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[336.540398] [043/130] (845s left) kms_big_fb (x-tiled-8bpp-rotate-0)
[336.664279] Starting subtest: x-tiled-8bpp-rotate-0
[337.766291] Subtest x-tiled-8bpp-rotate-0: SUCCESS (1.101s)
[337.896704] [044/130] (843s left) kms_scaling_modes (scaling-mode-none)
[337.992080] Starting subtest: scaling-mode-none
[337.994324] Starting dynamic subtest: pipe-A-eDP-1
[338.120495] Dynamic subtest pipe-A-eDP-1: SKIP (0.126s)
[338.134702] Starting dynamic subtest: pipe-B-eDP-1
[338.172106] Dynamic subtest pipe-B-eDP-1: SKIP (0.049s)
[338.185568] Starting dynamic subtest: pipe-C-eDP-1
[338.218719] Dynamic subtest pipe-C-eDP-1: SKIP (0.045s)
[338.228413] Starting dynamic subtest: pipe-D-eDP-1
[338.267918] Dynamic subtest pipe-D-eDP-1: SKIP (0.048s)
[338.271647] Subtest scaling-mode-none: SKIP (0.274s)
[338.395812] [045/130] (843s left) kms_cursor_crc (cursor-onscreen-128x128)
[338.580505] Starting subtest: cursor-onscreen-128x128
[338.584856] Starting dynamic subtest: pipe-A-eDP-1
[340.846396] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.261s)
[340.875361] Starting dynamic subtest: pipe-D-eDP-1
[343.814573] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.946s)
[343.817003] Subtest cursor-onscreen-128x128: SUCCESS (5.232s)
[344.601224] [046/130] (837s left) gem_ctx_persistence (engines-hang)
[344.667396] Starting subtest: engines-hang
[344.669732] Starting dynamic subtest: rcs0
[344.769081] Dynamic subtest rcs0: SUCCESS (0.101s)
[344.780220] Starting dynamic subtest: bcs0
[344.876950] Dynamic subtest bcs0: SUCCESS (0.107s)
[344.887643] Starting dynamic subtest: vcs0
[344.977743] Dynamic subtest vcs0: SUCCESS (0.100s)
[344.987100] Starting dynamic subtest: vcs1
[345.077630] Dynamic subtest vcs1: SUCCESS (0.098s)
[345.088754] Starting dynamic subtest: vecs0
[345.174063] Dynamic subtest vecs0: SUCCESS (0.095s)
[345.184580] Starting dynamic subtest: ccs0
[345.270660] Dynamic subtest ccs0: SUCCESS (0.096s)
[345.273337] Subtest engines-hang: SUCCESS (0.604s)
[345.348320] [047/130] (836s left) gem_eio (in-flight-immediate)
[345.574891] Starting subtest: in-flight-immediate
[354.402439] Subtest in-flight-immediate: SUCCESS (8.825s)
[354.585107] [FACT gem_eio (in-flight-immediate)] new: kernel.kmod_is_loaded.vgem: true
[354.587985] [048/130] (827s left) syncobj_basic (bad-flags-fd-to-handle)
[354.654830] Starting subtest: bad-flags-fd-to-handle
[354.657192] Subtest bad-flags-fd-to-handle: SUCCESS (0.000s)
[354.761903] [049/130] (827s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-rc-ccs)
[354.862894] Starting subtest: ccs-on-another-bo-y-tiled-gen12-rc-ccs
[354.865370] Starting dynamic subtest: pipe-A-eDP-1
[354.889192] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[354.904660] Starting dynamic subtest: pipe-B-eDP-1
[354.927922] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[354.938302] Starting dynamic subtest: pipe-C-eDP-1
[354.960123] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[354.968961] Starting dynamic subtest: pipe-D-eDP-1
[354.992703] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[354.997021] Subtest ccs-on-another-bo-y-tiled-gen12-rc-ccs: SKIP (0.003s)
[357.068546] [050/130] (824s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-msflip-blt)
[357.170690] Starting subtest: fbcpsr-2p-primscrn-indfb-msflip-blt
[357.174847] Subtest fbcpsr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[357.265314] [051/130] (824s left) api_intel_bb (object-noreloc-purge-cache-simple)
[357.327893] Starting subtest: object-noreloc-purge-cache-simple
[357.333762] Subtest object-noreloc-purge-cache-simple: SUCCESS (0.006s)
[357.454543] [052/130] (824s left) perf_pmu (busy)
[357.497502] Starting subtest: busy
[357.499695] Starting dynamic subtest: rcs0
[358.101907] Dynamic subtest rcs0: SUCCESS (0.604s)
[358.114148] Starting dynamic subtest: bcs0
[358.662663] Dynamic subtest bcs0: SUCCESS (0.559s)
[358.673543] Starting dynamic subtest: vcs0
[360.595706] Dynamic subtest vcs0: SUCCESS (1.929s)
[360.606821] Starting dynamic subtest: vcs1
[362.580247] Dynamic subtest vcs1: SUCCESS (1.982s)
[362.589696] Starting dynamic subtest: vecs0
[364.629940] Dynamic subtest vecs0: SUCCESS (2.047s)
[364.643887] Starting dynamic subtest: ccs0
[365.191331] Dynamic subtest ccs0: SUCCESS (0.561s)
[365.193750] Subtest busy: SUCCESS (7.692s)
[365.292129] [053/130] (816s left) prime_mmap (test_correct)
[365.356884] Starting subtest: test_correct
[365.359178] Starting dynamic subtest: test_correct-smem
[365.387987] Dynamic subtest test_correct-smem: SUCCESS (0.032s)
[365.391614] Subtest test_correct: SUCCESS (0.032s)
[365.500472] [054/130] (816s left) kms_lease (empty-lease)
[365.614596] Starting subtest: empty-lease
[365.617195] Starting dynamic subtest: pipe-A-eDP-1
[365.619668] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[365.634756] Starting dynamic subtest: pipe-B-eDP-1
[365.637007] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[365.645742] Starting dynamic subtest: pipe-C-eDP-1
[365.648100] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[365.656774] Starting dynamic subtest: pipe-D-eDP-1
[365.659100] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[365.661343] Subtest empty-lease: SUCCESS (0.007s)
[365.739286] [055/130] (816s left) kms_pipe_stress (stress-xrgb8888-untiled)
[365.845671] Starting subtest: stress-xrgb8888-untiled
[371.984721] Subtest stress-xrgb8888-untiled: SUCCESS (6.137s)
[372.034829] [056/130] (810s left) syncobj_wait (invalid-signal-bad-pad)
[372.098932] Starting subtest: invalid-signal-bad-pad
[372.101045] Subtest invalid-signal-bad-pad: SUCCESS (0.000s)
[372.205934] [057/130] (810s left) kms_flip (2x-modeset-vs-vblank-race)
[372.305542] Starting subtest: 2x-modeset-vs-vblank-race
[372.316229] Subtest 2x-modeset-vs-vblank-race: SKIP (0.010s)
[372.390369] [058/130] (809s left) gem_mmap_gtt (basic-wc)
[372.447126] Subtest basic-wc: SKIP (0.000s)
[372.511940] [059/130] (809s left) i915_suspend (fence-restore-tiled2untiled)
[372.577061] Starting subtest: fence-restore-tiled2untiled
[372.580527] Subtest fence-restore-tiled2untiled: SKIP (0.000s)
[372.672268] [060/130] (809s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-pwrite)
[372.764140] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-pwrite
[377.655055] Subtest fbcpsr-1p-offscren-pri-indfb-draw-pwrite: SUCCESS (4.891s)
[377.773229] [061/130] (804s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-wc)
[377.884359] Starting subtest: fbcpsr-rgb101010-draw-mmap-wc
[382.530128] Subtest fbcpsr-rgb101010-draw-mmap-wc: SUCCESS (4.646s)
[382.628364] [062/130] (799s left) drm_fdinfo (most-busy-check-all)
[382.672946] Starting subtest: most-busy-check-all
[382.675346] Starting dynamic subtest: rcs0
[382.679888] Dynamic subtest rcs0: SKIP (0.003s)
[382.688104] Starting dynamic subtest: bcs0
[382.692284] Dynamic subtest bcs0: SKIP (0.000s)
[382.700410] Starting dynamic subtest: vcs0
[382.704864] Dynamic subtest vcs0: SKIP (0.000s)
[382.712534] Starting dynamic subtest: vcs1
[382.716558] Dynamic subtest vcs1: SKIP (0.000s)
[382.725780] Starting dynamic subtest: vecs0
[382.729767] Dynamic subtest vecs0: SKIP (0.000s)
[382.738164] Starting dynamic subtest: ccs0
[382.742148] Dynamic subtest ccs0: SKIP (0.000s)
[382.746115] Subtest most-busy-check-all: SKIP (0.008s)
[382.776128] [063/130] (799s left) gem_exec_fence (syncobj-export)
[383.249186] Starting subtest: syncobj-export
[383.255260] Subtest syncobj-export: SUCCESS (0.005s)
[384.684623] [064/130] (797s left) gem_exec_whisper (basic-queues)
[384.752487] Starting subtest: basic-queues
[392.624380] Subtest basic-queues: SUCCESS (7.866s)
[392.803645] [065/130] (789s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area)
[397.804869] Starting subtest: pr-primary-plane-update-sf-dmg-area
[397.809405] Subtest pr-primary-plane-update-sf-dmg-area: SKIP (0.002s)
[398.655929] [066/130] (783s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[398.770886] Starting subtest: cursorB-vs-flipA-toggle
[398.775014] Subtest cursorB-vs-flipA-toggle: SKIP (0.001s)
[398.862824] [067/130] (783s left) sysfs_preempt_timeout (timeout)
[398.919486] Starting subtest: timeout
[398.921735] Starting dynamic subtest: vecs0
[408.623675] Dynamic subtest vecs0: SUCCESS (9.697s)
[408.638707] Starting dynamic subtest: ccs0
[409.983777] Dynamic subtest ccs0: SUCCESS (1.359s)
[409.995965] Starting dynamic subtest: vcs0
[419.616148] Dynamic subtest vcs0: SUCCESS (9.627s)
[419.624584] Starting dynamic subtest: bcs0
[420.935061] Dynamic subtest bcs0: SUCCESS (1.318s)
[420.944168] Starting dynamic subtest: rcs0
[422.262113] Dynamic subtest rcs0: SUCCESS (1.325s)
[422.273261] Starting dynamic subtest: vcs1
[431.581834] Dynamic subtest vcs1: SUCCESS (9.312s)
[431.584475] Subtest timeout: SUCCESS (32.651s)
[431.653653] [068/130] (750s left) kms_atomic_interruptible (universal-setplane-cursor)
[431.763118] Starting subtest: universal-setplane-cursor
[431.765562] Starting dynamic subtest: pipe-A-eDP-1
[439.184506] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.421s)
[439.186872] Subtest universal-setplane-cursor: SUCCESS (7.422s)
[440.018054] [069/130] (742s left) sw_sync (sync_busy_fork)
[440.058348] Starting subtest: sync_busy_fork
[441.069847] Subtest sync_busy_fork: SUCCESS (1.014s)
[441.129551] [070/130] (741s left) kms_getfb (getfb2-handle-not-fb)
[441.190114] Starting subtest: getfb2-handle-not-fb
[441.227917] Subtest getfb2-handle-not-fb: SUCCESS (0.041s)
[441.318746] [071/130] (741s left) gem_exec_nop (basic-sequential)
[441.404614] Starting subtest: basic-sequential
[455.164411] Subtest basic-sequential: SUCCESS (13.747s)
[455.386272] [072/130] (727s left) prime_mmap (test_map_unmap)
[455.439718] Starting subtest: test_map_unmap
[455.441589] Starting dynamic subtest: test_map_unmap-smem
[455.471091] Dynamic subtest test_map_unmap-smem: SUCCESS (0.033s)
[455.473506] Subtest test_map_unmap: SUCCESS (0.034s)
[455.590619] [073/130] (727s left) gem_partial_pwrite_pread (reads)
[455.655509] Subtest reads: SKIP (0.000s)
[455.738118] [074/130] (727s left) gem_softpin (evict-active-interruptible)
[455.814089] Starting subtest: evict-active-interruptible
[455.826461] Subtest evict-active-interruptible: SUCCESS (0.012s)
[455.946591] [075/130] (726s left) kms_chamelium_edid (hdmi-mode-timings)
[456.346685] Subtest hdmi-mode-timings: SKIP (0.000s)
[457.143448] [076/130] (725s left) syncobj_eventfd (binary-wait)
[457.210154] Starting subtest: binary-wait
[457.220632] Subtest binary-wait: SUCCESS (0.010s)
[457.339082] [077/130] (725s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-upscaling)
[457.446398] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-upscaling
[457.448681] Starting dynamic subtest: pipe-A-default-mode
[457.735708] Dynamic subtest pipe-A-default-mode: SKIP (0.285s)
[457.740483] Subtest flip-64bpp-yftile-to-16bpp-yftile-upscaling: SKIP (0.286s)
[458.574463] [078/130] (724s left) gem_mmap_gtt (basic-small-bo)
[458.629627] Subtest basic-small-bo: SKIP (0.000s)
[458.691878] [079/130] (724s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite)
[458.794290] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite
[458.798488] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[458.891292] [080/130] (724s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[458.986714] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[458.991315] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[459.079623] [081/130] (724s left) drm_fdinfo (all-busy-check-all)
[459.123872] Starting subtest: all-busy-check-all
[459.132060] Subtest all-busy-check-all: SKIP (0.005s)
[459.193473] [082/130] (723s left) kms_flip (wf_vblank-ts-check-interruptible)
[459.299153] Starting subtest: wf_vblank-ts-check-interruptible
[459.301421] Starting dynamic subtest: A-eDP1
[468.248817] Dynamic subtest A-eDP1: SUCCESS (8.917s)
[468.260049] Starting dynamic subtest: B-eDP1
[477.123130] Dynamic subtest B-eDP1: SUCCESS (8.920s)
[477.131450] Starting dynamic subtest: C-eDP1
[486.071749] Dynamic subtest C-eDP1: SUCCESS (8.909s)
[486.082286] Starting dynamic subtest: D-eDP1
[494.997680] Dynamic subtest D-eDP1: SUCCESS (8.929s)
[495.000208] Subtest wf_vblank-ts-check-interruptible: SUCCESS (35.683s)
[496.165759] [083/130] (686s left) kms_vrr (seamless-rr-switch-drrs)
[496.265423] Starting subtest: seamless-rr-switch-drrs
[496.272339] Subtest seamless-rr-switch-drrs: SKIP (0.000s)
[496.337119] [084/130] (686s left) core_getstats
[496.477980] [085/130] (686s left) syncobj_wait (multi-wait-for-submit-unsubmitted)
[496.536854] Starting subtest: multi-wait-for-submit-unsubmitted
[496.639545] Subtest multi-wait-for-submit-unsubmitted: SUCCESS (0.101s)
[496.713179] [086/130] (686s left) kms_psr (fbc-pr-cursor-render)
[496.817403] Starting subtest: fbc-pr-cursor-render
[496.821665] Subtest fbc-pr-cursor-render: SKIP (0.000s)
[496.883784] [087/130] (686s left) kms_busy (extended-modeset-hang-oldfb)
[496.989092] Starting subtest: extended-modeset-hang-oldfb
[496.991337] Starting dynamic subtest: pipe-A
[508.731323] Dynamic subtest pipe-A: SUCCESS (11.745s)
[508.740082] Starting dynamic subtest: pipe-D
[520.978370] Dynamic subtest pipe-D: SUCCESS (12.246s)
[520.980767] Subtest extended-modeset-hang-oldfb: SUCCESS (23.996s)
[521.760248] [088/130] (661s left) kms_big_fb (linear-32bpp-rotate-90)
[521.865541] Starting subtest: linear-32bpp-rotate-90
[521.885417] Subtest linear-32bpp-rotate-90: SKIP (0.019s)
[522.019225] [089/130] (661s left) kms_flip (2x-absolute-wf_vblank-interruptible)
[522.127929] Starting subtest: 2x-absolute-wf_vblank-interruptible
[522.141003] Subtest 2x-absolute-wf_vblank-interruptible: SKIP (0.011s)
[522.259129] [090/130] (661s left) kms_chamelium_hpd (common-hpd-after-suspend)
[522.647097] Subtest common-hpd-after-suspend: SKIP (0.000s)
[523.461692] [091/130] (659s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted)
[523.517687] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted
[523.623885] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.106s)
[523.695663] [092/130] (659s left) gem_render_copy (mixed-tiled-to-yf-tiled-ccs)
[523.766721] Starting subtest: mixed-tiled-to-yf-tiled-ccs
[523.771270] Subtest mixed-tiled-to-yf-tiled-ccs: SKIP (0.000s)
[523.942387] [093/130] (659s left) i915_pm_rc6_residency (rc6-fence)
[524.003692] Starting subtest: rc6-fence
[524.051341] Starting dynamic subtest: gt0
[536.389316] Dynamic subtest gt0: SUCCESS (12.323s)
[536.399440] Starting dynamic subtest: gt1
[551.571522] Dynamic subtest gt1: SUCCESS (15.195s)
[551.574900] Subtest rc6-fence: SUCCESS (27.572s)
[551.644823] [094/130] (631s left) kms_psr (psr2-sprite-blt)
[551.751837] Starting subtest: psr2-sprite-blt
[551.754060] Starting dynamic subtest: eDP-1
[553.469667] Dynamic subtest eDP-1: SUCCESS (1.719s)
[553.472352] Subtest psr2-sprite-blt: SUCCESS (1.720s)
[554.333667] [095/130] (629s left) gem_render_copy (y-tiled-to-vebox-linear)
[554.395120] Starting subtest: y-tiled-to-vebox-linear
[554.399762] Subtest y-tiled-to-vebox-linear: SKIP (0.000s)
[554.573443] [096/130] (629s left) kms_psr (fbc-psr2-primary-blt)
[554.662385] Starting subtest: fbc-psr2-primary-blt
[554.664657] Starting dynamic subtest: eDP-1
[554.669195] Dynamic subtest eDP-1: SKIP (0.000s)
[554.673470] Subtest fbc-psr2-primary-blt: SKIP (0.001s)
[554.733194] [097/130] (628s left) prime_mmap_kms (buffer-sharing)
[554.823820] Starting subtest: buffer-sharing
[555.273589] Subtest buffer-sharing: SUCCESS (0.446s)
[556.055478] [098/130] (627s left) kms_frontbuffer_tracking (psr-rgb565-draw-pwrite)
[556.173555] Starting subtest: psr-rgb565-draw-pwrite
[560.983933] Subtest psr-rgb565-draw-pwrite: SUCCESS (4.813s)
[561.093462] [099/130] (622s left) kms_invalid_mode (clock-too-high)
[561.195081] Starting subtest: clock-too-high
[561.197594] Starting dynamic subtest: pipe-A-eDP-1
[561.202317] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[561.213279] Starting dynamic subtest: pipe-B-eDP-1
[561.217593] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[561.226315] Starting dynamic subtest: pipe-C-eDP-1
[561.230320] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[561.238142] Starting dynamic subtest: pipe-D-eDP-1
[561.242246] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[561.246297] Subtest clock-too-high: SKIP (0.004s)
[561.280290] [100/130] (622s left) kms_plane (plane-position-covered)
[561.380262] Starting subtest: plane-position-covered
[561.906072] Starting dynamic subtest: pipe-A-plane-1
[563.166624] Dynamic subtest pipe-A-plane-1: SUCCESS (1.260s)
[563.177300] Starting dynamic subtest: pipe-A-plane-2
[564.377836] Dynamic subtest pipe-A-plane-2: SUCCESS (1.210s)
[564.387650] Starting dynamic subtest: pipe-A-plane-3
[565.601110] Dynamic subtest pipe-A-plane-3: SUCCESS (1.223s)
[565.610250] Starting dynamic subtest: pipe-A-plane-4
[566.822664] Dynamic subtest pipe-A-plane-4: SUCCESS (1.221s)
[566.832223] Starting dynamic subtest: pipe-A-plane-5
[566.861061] Dynamic subtest pipe-A-plane-5: SUCCESS (0.037s)
[567.989894] Starting dynamic subtest: pipe-B-plane-1
[569.191268] Dynamic subtest pipe-B-plane-1: SUCCESS (1.211s)
[569.201435] Starting dynamic subtest: pipe-B-plane-2
[570.407758] Dynamic subtest pipe-B-plane-2: SUCCESS (1.216s)
[570.417872] Starting dynamic subtest: pipe-B-plane-3
[571.630125] Dynamic subtest pipe-B-plane-3: SUCCESS (1.222s)
[571.639917] Starting dynamic subtest: pipe-B-plane-4
[572.882814] Dynamic subtest pipe-B-plane-4: SUCCESS (1.252s)
[572.892629] Starting dynamic subtest: pipe-B-plane-5
[572.927269] Dynamic subtest pipe-B-plane-5: SUCCESS (0.043s)
[572.929177] Subtest plane-position-covered: SUCCESS (11.545s)
[573.756800] [101/130] (610s left) gem_mmap_offset (mmap-boundaries)
[573.827001] Starting subtest: mmap-boundaries
[573.829006] Starting dynamic subtest: smem0
[574.882130] Dynamic subtest smem0: SUCCESS (1.054s)
[574.885313] Subtest mmap-boundaries: SUCCESS (1.055s)
[575.081659] [102/130] (608s left) gem_eio (banned)
[575.295591] Starting subtest: banned
[575.910942] Subtest banned: SUCCESS (0.615s)
[576.076249] [103/130] (607s left) gem_lmem_swapping (parallel-random-verify)
[576.122852] Subtest parallel-random-verify: SKIP (0.000s)
[576.163507] [104/130] (607s left) kms_ccs (random-ccs-data-y-tiled-gen12-rc-ccs-cc)
[576.256767] Starting subtest: random-ccs-data-y-tiled-gen12-rc-ccs-cc
[576.258758] Starting dynamic subtest: pipe-A-eDP-1
[576.279655] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[576.289872] Starting dynamic subtest: pipe-B-eDP-1
[576.309161] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[576.317374] Starting dynamic subtest: pipe-C-eDP-1
[576.336992] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[576.344919] Starting dynamic subtest: pipe-D-eDP-1
[576.365979] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[576.370051] Subtest random-ccs-data-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[578.414580] [105/130] (605s left) kms_ccs (crc-sprite-planes-basic-4-tiled-bmg-ccs)
[578.510926] Starting subtest: crc-sprite-planes-basic-4-tiled-bmg-ccs
[578.515392] Subtest crc-sprite-planes-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[579.649378] [106/130] (604s left) gem_exec_balancer (nop)
[579.700864] Starting subtest: nop
[626.212633] Subtest nop: SUCCESS (46.511s)
[626.497115] [107/130] (557s left) syncobj_timeline (multi-wait-available-submitted-signaled)
[626.559817] Starting subtest: multi-wait-available-submitted-signaled
[626.562140] Subtest multi-wait-available-submitted-signaled: SUCCESS (0.000s)
[626.664064] [108/130] (557s left) gem_exec_schedule (u-semaphore-noskip)
[626.737951] Starting subtest: u-semaphore-noskip
[627.177361] Subtest u-semaphore-noskip: SUCCESS (0.444s)
[627.537900] [109/130] (556s left) kms_async_flips (invalid-async-flip)
[627.634099] Starting subtest: invalid-async-flip
[627.638265] Subtest invalid-async-flip: SKIP (0.000s)
[628.864157] [110/130] (555s left) kms_vblank (ts-continuation-dpms-suspend)
[628.964243] Starting subtest: ts-continuation-dpms-suspend
[628.966615] Starting dynamic subtest: pipe-A-eDP-1
[646.124392] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.863s)
[646.139319] Starting dynamic subtest: pipe-D-eDP-1
[663.824526] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.181s)
[663.827405] Subtest ts-continuation-dpms-suspend: SUCCESS (4.049s)
[664.621357] [111/130] (550s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-sf)
[669.682676] Starting subtest: fbc-psr2-overlay-plane-move-continuous-exceed-sf
[669.685492] Starting dynamic subtest: pipe-A-eDP-1
[670.482485] Dynamic subtest pipe-A-eDP-1: SKIP (0.796s)
[670.491729] Starting dynamic subtest: pipe-B-eDP-1
[671.607730] Dynamic subtest pipe-B-eDP-1: SKIP (1.122s)
[671.612618] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-sf: SKIP (1.923s)
[672.783752] [112/130] (542s left) gem_exec_schedule (preempt-queue-chain)
[672.875590] Subtest preempt-queue-chain: SKIP (0.000s)
[673.150623] [113/130] (541s left) gem_vm_create (execbuf)
[673.215897] Starting subtest: execbuf
[673.223041] Subtest execbuf: SUCCESS (0.008s)
[673.368245] [114/130] (541s left) kms_plane_scaling (planes-downscale-factor-0-5-upscale-20x20)
[673.463344] Starting subtest: planes-downscale-factor-0-5-upscale-20x20
[673.465864] Starting dynamic subtest: pipe-A
[673.475330] Dynamic subtest pipe-A: SKIP (0.005s)
[673.485938] Starting dynamic subtest: pipe-B
[673.494740] Dynamic subtest pipe-B: SKIP (0.015s)
[673.503885] Starting dynamic subtest: pipe-C
[673.514681] Dynamic subtest pipe-C: SKIP (0.015s)
[673.523344] Starting dynamic subtest: pipe-D
[673.532168] Dynamic subtest pipe-D: SKIP (0.015s)
[673.536409] Subtest planes-downscale-factor-0-5-upscale-20x20: SKIP (0.052s)
[673.620002] [115/130] (541s left) gem_softpin (overlap)
[673.689433] Starting subtest: overlap
[674.437478] Subtest overlap: SUCCESS (0.746s)
[674.574133] [116/130] (540s left) syncobj_wait (invalid-wait-illegal-handle)
[674.635749] Starting subtest: invalid-wait-illegal-handle
[674.638344] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[674.751350] [117/130] (540s left) gem_pxp (reject-modify-context-protection-off-1)
[674.872562] Starting subtest: reject-modify-context-protection-off-1
[674.875552] Subtest reject-modify-context-protection-off-1: SUCCESS (0.001s)
[674.970577] [118/130] (540s left) kms_pipe_b_c_ivb (pipe-b-double-modeset-then-modeset-pipe-c)
[675.032737] Subtest pipe-B-double-modeset-then-modeset-pipe-C: SKIP (0.000s)
[675.088047] [119/130] (540s left) kms_psr (psr2-cursor-render)
[675.196052] Starting subtest: psr2-cursor-render
[675.198503] Starting dynamic subtest: eDP-1
[676.856650] Dynamic subtest eDP-1: SUCCESS (1.658s)
[676.859558] Subtest psr2-cursor-render: SUCCESS (1.659s)
[677.633445] [120/130] (537s left) kms_big_fb (4-tiled-addfb)
[677.746770] Starting subtest: 4-tiled-addfb
[677.749513] Subtest 4-tiled-addfb: SUCCESS (0.000s)
[677.848973] [121/130] (537s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[677.956748] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[677.961597] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s)
[678.055138] [122/130] (537s left) syncobj_timeline (invalid-multi-wait-unsubmitted)
[678.121689] Starting subtest: invalid-multi-wait-unsubmitted
[678.124522] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[678.227199] [123/130] (537s left) kms_cursor_legacy (cursor-vs-flip-atomic)
[678.348491] Starting subtest: cursor-vs-flip-atomic
[707.797342] Subtest cursor-vs-flip-atomic: SUCCESS (29.440s)
[708.670254] [124/130] (506s left) gem_partial_pwrite_pread (writes-after-reads-snoop)
[708.733741] Subtest writes-after-reads-snoop: SKIP (0.000s)
[708.793484] [125/130] (506s left) syncobj_wait (invalid-reset-zero-handles)
[708.857590] Starting subtest: invalid-reset-zero-handles
[708.860559] Subtest invalid-reset-zero-handles: SUCCESS (0.000s)
[708.957407] [126/130] (506s left) kms_dither (fb-8bpc-vs-panel-6bpc)
[709.062151] Starting subtest: fb-8bpc-vs-panel-6bpc
[709.064637] Starting dynamic subtest: pipe-A-eDP-1
[710.450368] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.389s)
[710.452974] Subtest fb-8bpc-vs-panel-6bpc: SUCCESS (1.390s)
[711.307906] [127/130] (504s left) kms_cursor_edge_walk (256x256-top-edge)
[711.411531] Starting subtest: 256x256-top-edge
[711.414150] Starting dynamic subtest: pipe-A-eDP-1
[714.877577] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.462s)
[714.887154] Starting dynamic subtest: pipe-D-eDP-1
[717.980357] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.103s)
[717.982758] Subtest 256x256-top-edge: SUCCESS (6.568s)
[718.783864] [128/130] (496s left) kms_big_fb (linear-16bpp-rotate-180)
[718.897140] Starting subtest: linear-16bpp-rotate-180
[720.273388] Subtest linear-16bpp-rotate-180: SUCCESS (1.377s)
[720.481356] [129/130] (495s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-pwrite)
[720.588821] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-pwrite
[726.126863] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (5.542s)
[726.238873] [130/130] (489s left) kms_plane_scaling (plane-upscale-factor-0-25-with-pixel-format)
[726.333432] Starting subtest: plane-upscale-factor-0-25-with-pixel-format
[726.336247] Starting dynamic subtest: pipe-A
[727.394228] Dynamic subtest pipe-A: SUCCESS (1.061s)
[727.405902] Starting dynamic subtest: pipe-B
[729.476140] Dynamic subtest pipe-B: SUCCESS (2.080s)
[729.487080] Starting dynamic subtest: pipe-C
[730.652270] Dynamic subtest pipe-C: SUCCESS (1.175s)
[730.671961] Starting dynamic subtest: pipe-D
[731.770647] Dynamic subtest pipe-D: SUCCESS (1.117s)
[731.773440] Subtest plane-upscale-factor-0-25-with-pixel-format: SUCCESS (5.436s)
[732.949687] Closing watchdogs
Done.