[57.469750] Initializing watchdogs
[57.469866]   /dev/watchdog0
[57.484594] [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.489116] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.491392] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.494716] [001/133] (960s left) i915_drm_fdinfo (busy-idle)
[57.556960] Starting subtest: busy-idle
[57.559065] Starting dynamic subtest: rcs0
[57.580917] Dynamic subtest rcs0: SKIP (0.022s)
[57.589823] Starting dynamic subtest: bcs0
[57.599678] Dynamic subtest bcs0: SKIP (0.019s)
[57.606622] Starting dynamic subtest: vcs0
[57.611075] Dynamic subtest vcs0: SKIP (0.000s)
[57.618694] Starting dynamic subtest: vcs1
[57.622765] Dynamic subtest vcs1: SKIP (0.000s)
[57.630418] Starting dynamic subtest: vecs0
[57.634832] Dynamic subtest vecs0: SKIP (0.000s)
[57.641897] Starting dynamic subtest: ccs0
[57.646236] Dynamic subtest ccs0: SKIP (0.000s)
[57.650577] Subtest busy-idle: SKIP (0.043s)
[57.683562] [002/133] (960s left) kms_hdr (static-toggle-dpms)
[57.789607] Starting subtest: static-toggle-dpms
[57.795922] Subtest static-toggle-dpms: SKIP (0.000s)
[57.854367] [003/133] (960s left) gem_ctx_param (basic-default)
[57.898677] Starting subtest: basic-default
[57.901081] Subtest basic-default: SUCCESS (0.000s)
[57.962616] [004/133] (960s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc)
[58.049781] Starting subtest: psr-rgb565-draw-mmap-wc
[64.944132] Subtest psr-rgb565-draw-mmap-wc: SUCCESS (6.897s)
[65.057853] [005/133] (953s left) gem_render_copy (yf-tiled-ccs-to-linear)
[65.115950] Starting subtest: yf-tiled-ccs-to-linear
[65.121692] Subtest yf-tiled-ccs-to-linear: SKIP (0.000s)
[65.308515] [006/133] (952s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-render)
[65.401633] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-render
[72.240121] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-render: SUCCESS (6.840s)
[72.407155] [007/133] (945s left) i915_pm_rps (min-max-config-loaded)
[72.461977] Starting subtest: min-max-config-loaded
[72.466452] Subtest min-max-config-loaded: SKIP (0.001s)
[72.566782] [008/133] (945s left) kms_frontbuffer_tracking (fbcpsr-shrfb-scaledprimary)
[72.660388] Starting subtest: fbcpsr-shrfb-scaledprimary
[78.537705] Subtest fbcpsr-shrfb-scaledprimary: SUCCESS (5.879s)
[78.668740] [009/133] (939s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-onoff)
[78.775040] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-onoff
[85.768336] Subtest fbcpsr-1p-primscrn-cur-indfb-onoff: SUCCESS (6.996s)
[85.913084] [010/133] (932s left) kms_rotation_crc (cursor-rotation-180)
[86.006705] Starting subtest: cursor-rotation-180
[87.532934] Subtest cursor-rotation-180: SUCCESS (1.526s)
[88.708710] [011/133] (929s left) syncobj_wait (reset-multiple-signaled)
[88.767876] Starting subtest: reset-multiple-signaled
[88.770204] Subtest reset-multiple-signaled: SUCCESS (0.000s)
[88.861268] [012/133] (929s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-signaled)
[88.910969] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[88.913393] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.001s)
[89.026698] [013/133] (929s left) kms_psr (psr2-basic)
[89.132944] Starting subtest: psr2-basic
[89.135284] Starting dynamic subtest: eDP-1
[90.783935] Dynamic subtest eDP-1: SUCCESS (1.651s)
[90.786332] Subtest psr2-basic: SUCCESS (1.652s)
[91.570956] [014/133] (926s left) kms_vblank (ts-continuation-modeset)
[91.664348] Starting subtest: ts-continuation-modeset
[91.666692] Starting dynamic subtest: pipe-A-eDP-1
[93.145889] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.478s)
[93.159062] Starting dynamic subtest: pipe-D-eDP-1
[95.310724] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.164s)
[95.313089] Subtest ts-continuation-modeset: SUCCESS (3.645s)
[96.108344] [015/133] (922s left) syncobj_timeline (wait-for-submit-snapshot)
[96.167058] Starting subtest: wait-for-submit-snapshot
[96.248608] Subtest wait-for-submit-snapshot: SUCCESS (0.082s)
[96.322674] [016/133] (922s left) gem_mmap_gtt (cpuset-medium-copy-odd)
[96.378292] Subtest cpuset-medium-copy-odd: SKIP (0.000s)
[96.438687] [017/133] (922s left) gem_ctx_param (invalid-get-ringsize)
[96.505681] Starting subtest: invalid-get-ringsize
[96.508085] Subtest invalid-get-ringsize: SUCCESS (0.002s)
[96.615426] [018/133] (922s left) gem_tiled_fence_blits (normal)
[96.672049] Subtest normal: SKIP (0.000s)
[96.754356] [019/133] (921s left) gem_linear_blits (interruptible)
[97.068489] Starting subtest: interruptible
[101.440755] Subtest interruptible: SUCCESS (4.371s)
[101.566453] [020/133] (917s left) kms_big_fb (x-tiled-64bpp-rotate-270)
[101.717339] Starting subtest: x-tiled-64bpp-rotate-270
[101.741266] Subtest x-tiled-64bpp-rotate-270: SKIP (0.021s)
[101.850916] [021/133] (916s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-modifiers)
[101.941173] Starting subtest: plane-scaler-with-clipping-clamping-modifiers
[101.943545] Starting dynamic subtest: pipe-A
[102.301297] Dynamic subtest pipe-A: SUCCESS (0.362s)
[102.310222] Starting dynamic subtest: pipe-B
[103.704274] Dynamic subtest pipe-B: SUCCESS (1.402s)
[103.713262] Starting dynamic subtest: pipe-C
[105.156242] Dynamic subtest pipe-C: SUCCESS (1.452s)
[105.165814] Starting dynamic subtest: pipe-D
[106.569529] Dynamic subtest pipe-D: SUCCESS (1.413s)
[106.571926] Subtest plane-scaler-with-clipping-clamping-modifiers: SUCCESS (4.631s)
[107.744723] [022/133] (911s left) kms_pm_rpm (system-suspend-modeset)
[109.313571] Starting subtest: system-suspend-modeset
[129.252053] Subtest system-suspend-modeset: SUCCESS (4.486s)
[129.629162] [023/133] (889s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted-signaled)
[129.711040] Starting subtest: multi-wait-all-for-submit-available-submitted-signaled
[129.713735] Subtest multi-wait-all-for-submit-available-submitted-signaled: SUCCESS (0.001s)
[129.801297] [024/133] (889s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling)
[129.912772] Starting subtest: flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling
[129.917202] Subtest flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.000s)
[129.982035] [025/133] (888s left) gem_ctx_param (non-root-set)
[130.033363] Starting subtest: non-root-set
[130.053876] Subtest non-root-set: SUCCESS (0.021s)
[130.151378] [026/133] (888s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[130.247218] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-cpu
[130.252135] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[130.356335] [027/133] (888s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[130.459706] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[131.285528] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SUCCESS (0.826s)
[131.479321] [028/133] (887s left) gem_ctx_persistence (file)
[131.537514] Starting subtest: file
[131.644203] Subtest file: SUCCESS (0.109s)
[131.775766] [029/133] (887s left) kms_flip_scaled_crc (flip-32bpp-xtile-to-64bpp-xtile-downscaling)
[131.863402] Starting subtest: flip-32bpp-xtile-to-64bpp-xtile-downscaling
[131.865583] Starting dynamic subtest: pipe-A-default-mode
[132.978426] Dynamic subtest pipe-A-default-mode: SKIP (1.112s)
[132.982435] Subtest flip-32bpp-xtile-to-64bpp-xtile-downscaling: SKIP (1.114s)
[133.130307] [030/133] (885s left) kms_plane_scaling (plane-upscale-factor-0-25-with-pixel-format)
[133.215577] Starting subtest: plane-upscale-factor-0-25-with-pixel-format
[133.217727] Starting dynamic subtest: pipe-A
[134.265257] Dynamic subtest pipe-A: SUCCESS (1.052s)
[134.273493] Starting dynamic subtest: pipe-B
[136.350668] Dynamic subtest pipe-B: SUCCESS (2.085s)
[136.364173] Starting dynamic subtest: pipe-C
[137.542754] Dynamic subtest pipe-C: SUCCESS (1.189s)
[137.551813] Starting dynamic subtest: pipe-D
[138.697676] Dynamic subtest pipe-D: SUCCESS (1.154s)
[138.700191] Subtest plane-upscale-factor-0-25-with-pixel-format: SUCCESS (5.486s)
[139.905021] [031/133] (879s left) kms_flip (flip-vs-dpms-off-vs-modeset-interruptible)
[140.009242] Starting subtest: flip-vs-dpms-off-vs-modeset-interruptible
[140.011547] Starting dynamic subtest: A-eDP1
[143.622692] Dynamic subtest A-eDP1: SUCCESS (3.609s)
[143.634347] Starting dynamic subtest: B-eDP1
[146.810712] Dynamic subtest B-eDP1: SUCCESS (3.187s)
[146.820553] Starting dynamic subtest: C-eDP1
[149.906224] Dynamic subtest C-eDP1: SUCCESS (3.097s)
[149.915166] Starting dynamic subtest: D-eDP1
[153.093756] Dynamic subtest D-eDP1: SUCCESS (3.186s)
[153.096100] Subtest flip-vs-dpms-off-vs-modeset-interruptible: SUCCESS (13.087s)
[153.888407] [032/133] (865s left) gem_exec_parallel (contexts)
[153.953897] Starting subtest: contexts
[153.956300] Starting dynamic subtest: rcs0
[157.115767] Dynamic subtest rcs0: SUCCESS (3.160s)
[157.125972] Starting dynamic subtest: bcs0
[160.666492] Dynamic subtest bcs0: SUCCESS (3.548s)
[160.678348] Starting dynamic subtest: vcs0
[164.839580] Dynamic subtest vcs0: SUCCESS (4.170s)
[164.851495] Starting dynamic subtest: vcs1
[168.875769] Dynamic subtest vcs1: SUCCESS (4.034s)
[168.884397] Starting dynamic subtest: vecs0
[172.996397] Dynamic subtest vecs0: SUCCESS (4.118s)
[173.006419] Starting dynamic subtest: ccs0
[176.597689] Dynamic subtest ccs0: SUCCESS (3.598s)
[176.600378] Subtest contexts: SUCCESS (22.645s)
[176.800847] [033/133] (842s left) gem_exec_alignment (many)
[176.874474] Starting subtest: many
[200.661583] Subtest many: SUCCESS (23.787s)
[203.277148] [034/133] (816s left) kms_psr (fbc-pr-no-drrs)
[203.427489] Starting subtest: fbc-pr-no-drrs
[203.431926] Subtest fbc-pr-no-drrs: SKIP (0.000s)
[203.507625] [035/133] (815s left) kms_vrr (flip-suspend)
[203.606142] Starting subtest: flip-suspend
[203.612396] Subtest flip-suspend: SKIP (0.000s)
[203.675811] [036/133] (815s left) i915_module_load (reload)
[203.720287] Starting subtest: reload
[207.100298] Subtest reload: SUCCESS (3.381s)
[207.145788] [037/133] (812s left) gem_vm_create (invalid-destroy)
[208.294142] Starting subtest: invalid-destroy
[208.296433] Subtest invalid-destroy: SUCCESS (0.002s)
[208.416973] [038/133] (811s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-rc-ccs)
[208.503140] Starting subtest: crc-primary-suspend-4-tiled-dg2-rc-ccs
[208.505417] Starting dynamic subtest: pipe-A-eDP-1
[208.514575] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[208.523154] Starting dynamic subtest: pipe-B-eDP-1
[208.532284] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[208.540556] Starting dynamic subtest: pipe-C-eDP-1
[208.549602] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[208.557164] Starting dynamic subtest: pipe-D-eDP-1
[208.565763] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[208.569780] Subtest crc-primary-suspend-4-tiled-dg2-rc-ccs: SKIP (0.005s)
[211.009310] [039/133] (808s left) kms_dsc (dsc-with-bpc-formats)
[211.106329] Starting subtest: dsc-with-bpc-formats
[211.220648] Subtest dsc-with-bpc-formats: SKIP (0.003s)
[211.258982] [040/133] (808s left) kms_flip (dpms-off-confusion)
[211.348879] Starting subtest: dpms-off-confusion
[211.351246] Starting dynamic subtest: A-eDP1
[220.117445] Dynamic subtest A-eDP1: SUCCESS (8.768s)
[220.130150] Starting dynamic subtest: B-eDP1
[228.845367] Dynamic subtest B-eDP1: SUCCESS (8.723s)
[228.855976] Starting dynamic subtest: C-eDP1
[237.472418] Dynamic subtest C-eDP1: SUCCESS (8.622s)
[237.486282] Starting dynamic subtest: D-eDP1
[246.104331] Dynamic subtest D-eDP1: SUCCESS (8.629s)
[246.109851] Subtest dpms-off-confusion: SUCCESS (34.754s)
[247.278003] [041/133] (772s left) gem_exec_schedule (submit-early-slice)
[247.356800] Starting subtest: submit-early-slice
[247.359050] Starting dynamic subtest: rcs0
[247.389567] Dynamic subtest rcs0: SUCCESS (0.041s)
[247.404169] Starting dynamic subtest: bcs0
[247.431784] Dynamic subtest bcs0: SUCCESS (0.041s)
[247.440409] Starting dynamic subtest: vcs0
[247.468767] Dynamic subtest vcs0: SUCCESS (0.036s)
[247.477722] Starting dynamic subtest: vcs1
[247.506868] Dynamic subtest vcs1: SUCCESS (0.037s)
[247.516385] Starting dynamic subtest: vecs0
[247.543337] Dynamic subtest vecs0: SUCCESS (0.036s)
[247.552289] Starting dynamic subtest: ccs0
[247.575237] Dynamic subtest ccs0: SUCCESS (0.031s)
[247.577563] Subtest submit-early-slice: SUCCESS (0.228s)
[249.421184] [042/133] (770s left) gem_exec_schedule (noreorder)
[249.598273] Starting subtest: noreorder
[249.600724] Starting dynamic subtest: rcs0
[249.652770] Dynamic subtest rcs0: SUCCESS (0.054s)
[249.662374] Starting dynamic subtest: vcs0
[251.378379] Dynamic subtest vcs0: SUCCESS (1.722s)
[251.389569] Starting dynamic subtest: vcs1
[253.357543] Dynamic subtest vcs1: SUCCESS (1.977s)
[253.369529] Starting dynamic subtest: vecs0
[255.356518] Dynamic subtest vecs0: SUCCESS (1.995s)
[255.367996] Starting dynamic subtest: ccs0
[255.419410] Dynamic subtest ccs0: SUCCESS (0.062s)
[255.421865] Subtest noreorder: SUCCESS (5.821s)
[255.603643] [043/133] (764s left) gem_close (many-handles-one-vma)
[255.663328] Starting subtest: many-handles-one-vma
[255.704240] Subtest many-handles-one-vma: SUCCESS (0.041s)
[255.787313] [044/133] (763s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-gtt)
[255.894644] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-gtt
[255.898614] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[255.990673] [045/133] (763s left) kms_plane_alpha_blend (alpha-basic)
[256.094675] Starting subtest: alpha-basic
[256.380809] Starting dynamic subtest: pipe-A-eDP-1
[258.310074] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.928s)
[258.339314] Starting dynamic subtest: pipe-D-eDP-1
[260.966961] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.635s)
[260.969381] Subtest alpha-basic: SUCCESS (4.872s)
[261.839619] [046/133] (757s left) kms_psr (psr2-cursor-plane-move)
[261.947173] Starting subtest: psr2-cursor-plane-move
[261.949427] Starting dynamic subtest: eDP-1
[263.609951] Dynamic subtest eDP-1: SUCCESS (1.664s)
[263.612187] Subtest psr2-cursor-plane-move: SUCCESS (1.665s)
[264.418322] [047/133] (755s left) kms_big_fb (y-tiled-64bpp-rotate-270)
[264.516979] Starting subtest: y-tiled-64bpp-rotate-270
[264.521358] Subtest y-tiled-64bpp-rotate-270: SKIP (0.000s)
[264.612199] [048/133] (755s left) dmabuf_sync_file (export-multiwait)
[264.678369] Starting subtest: export-multiwait
[264.680750] Subtest export-multiwait: SUCCESS (0.002s)
[264.734119] [FACT dmabuf_sync_file (export-multiwait)] new: kernel.kmod_is_loaded.vgem: true
[264.737438] [049/133] (755s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[264.834096] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc
[264.838477] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[264.932626] [050/133] (755s left) gem_mmap_gtt (basic-small-copy)
[264.987803] Subtest basic-small-copy: SKIP (0.000s)
[265.046263] [051/133] (754s left) gem_exec_fence (syncobj-backward-timeline-chain-engines)
[265.575697] Starting subtest: syncobj-backward-timeline-chain-engines
[265.686251] Subtest syncobj-backward-timeline-chain-engines: SUCCESS (0.111s)
[267.468405] [052/133] (752s left) gem_userptr_blits (process-exit-busy)
[267.524953] Starting subtest: process-exit-busy
[267.541140] Subtest process-exit-busy: SUCCESS (0.018s)
[267.659792] [053/133] (752s left) kms_big_fb (linear-64bpp-rotate-90)
[267.758741] Starting subtest: linear-64bpp-rotate-90
[267.778473] Subtest linear-64bpp-rotate-90: SKIP (0.019s)
[267.882787] [054/133] (752s left) kms_busy (extended-modeset-hang-oldfb-with-reset)
[267.980533] Starting subtest: extended-modeset-hang-oldfb-with-reset
[267.982821] Starting dynamic subtest: pipe-A
[279.576175] Dynamic subtest pipe-A: SUCCESS (11.591s)
[279.587962] Starting dynamic subtest: pipe-D
[291.844867] Dynamic subtest pipe-D: SUCCESS (12.266s)
[291.846818] Subtest extended-modeset-hang-oldfb-with-reset: SUCCESS (23.862s)
[292.645351] [055/133] (727s left) gem_readwrite (new-obj)
[292.701737] Subtest new-obj: SKIP (0.000s)
[292.772910] [056/133] (727s left) gem_exec_whisper (basic-forked-all)
[292.837886] Starting subtest: basic-forked-all
[306.184480] Subtest basic-forked-all: SUCCESS (13.346s)
[306.369220] [057/133] (713s left) gem_mmap_offset (mmap-boundaries)
[306.424153] Starting subtest: mmap-boundaries
[306.426175] Starting dynamic subtest: smem0
[306.715222] Dynamic subtest smem0: SUCCESS (0.292s)
[306.717780] Subtest mmap-boundaries: SUCCESS (0.293s)
[306.839537] [058/133] (713s left) kms_plane (pixel-format-4-tiled-modifier-source-clamping)
[306.943576] Starting subtest: pixel-format-4-tiled-modifier-source-clamping
[306.970442] Starting dynamic subtest: pipe-A-plane-0
[307.647970] Dynamic subtest pipe-A-plane-0: SUCCESS (0.678s)
[307.659137] Starting dynamic subtest: pipe-A-plane-3
[308.114393] Dynamic subtest pipe-A-plane-3: SUCCESS (0.466s)
[309.229808] Starting dynamic subtest: pipe-B-plane-0
[309.924197] Dynamic subtest pipe-B-plane-0: SUCCESS (0.700s)
[309.935245] Starting dynamic subtest: pipe-B-plane-3
[310.376613] Dynamic subtest pipe-B-plane-3: SUCCESS (0.455s)
[310.686984] Subtest pixel-format-4-tiled-modifier-source-clamping: SUCCESS (3.742s)
[311.507683] [059/133] (708s left) api_intel_allocator (reopen)
[311.571159] Starting subtest: reopen
[311.576329] Subtest reopen: SUCCESS (0.004s)
[311.702100] [060/133] (708s left) gem_mmap (pf-nonblock)
[311.763948] Subtest pf-nonblock: SKIP (0.000s)
[311.816012] [061/133] (708s left) syncobj_timeline (wait-any-snapshot)
[311.877683] Starting subtest: wait-any-snapshot
[311.957536] Subtest wait-any-snapshot: SUCCESS (0.081s)
[312.039481] [062/133] (708s left) i915_query (query-garbage)
[312.092998] Starting subtest: query-garbage
[312.094884] Subtest query-garbage: SUCCESS (0.000s)
[312.193899] [063/133] (708s left) syncobj_timeline (multi-wait-all-for-submit-signaled)
[312.252186] Starting subtest: multi-wait-all-for-submit-signaled
[312.254443] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.000s)
[312.337800] [064/133] (708s left) gem_render_copy (y-tiled-mc-ccs-to-y-tiled-ccs)
[312.390156] Starting subtest: y-tiled-mc-ccs-to-y-tiled-ccs
[312.394400] Subtest y-tiled-mc-ccs-to-y-tiled-ccs: SKIP (0.000s)
[312.572057] [065/133] (707s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[312.661658] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[312.676852] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.014s)
[312.753061] [066/133] (707s left) gem_create (create-valid-nonaligned)
[312.808973] Starting subtest: create-valid-nonaligned
[312.811132] Subtest create-valid-nonaligned: SUCCESS (0.000s)
[312.995123] [067/133] (707s left) gem_barrier_race (remote-request)
[313.065804] Starting subtest: remote-request
[313.068004] Starting dynamic subtest: rcs0
[318.272254] Dynamic subtest rcs0: SUCCESS (5.209s)
[318.276528] Subtest remote-request: SUCCESS (5.210s)
[318.406030] [068/133] (702s left) kms_prop_blob (blob-prop-core)
[318.471826] Starting subtest: blob-prop-core
[318.473939] Subtest blob-prop-core: SUCCESS (0.000s)
[318.578705] [069/133] (702s left) kms_frontbuffer_tracking (fbcpsr-suspend)
[318.667454] Starting subtest: fbcpsr-suspend
[359.628201] Subtest fbcpsr-suspend: SUCCESS (10.122s)
[359.754277] [070/133] (661s left) gem_mmap_gtt (basic-read)
[359.822477] Subtest basic-read: SKIP (0.000s)
[359.891288] [071/133] (660s left) kms_flip (2x-blocking-wf_vblank)
[359.981217] Starting subtest: 2x-blocking-wf_vblank
[359.992681] Subtest 2x-blocking-wf_vblank: SKIP (0.011s)
[360.095887] [072/133] (660s left) kms_psr (psr-sprite-plane-move)
[360.199153] Starting subtest: psr-sprite-plane-move
[360.201277] Starting dynamic subtest: eDP-1
[361.963895] Dynamic subtest eDP-1: SUCCESS (1.763s)
[361.966169] Subtest psr-sprite-plane-move: SUCCESS (1.764s)
[362.781789] [073/133] (658s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-msflip-blt)
[362.878129] Starting subtest: psr-2p-primscrn-indfb-msflip-blt
[362.883058] Subtest psr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[362.977056] [074/133] (657s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted-signa
[363.035332] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled
[363.141400] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.107s)
[363.222599] [075/133] (657s left) kms_psr (fbc-psr2-cursor-mmap-gtt)
[363.328255] Starting subtest: fbc-psr2-cursor-mmap-gtt
[363.330475] Starting dynamic subtest: eDP-1
[363.334498] Dynamic subtest eDP-1: SKIP (0.000s)
[363.338553] Subtest fbc-psr2-cursor-mmap-gtt: SKIP (0.001s)
[363.402197] [076/133] (657s left) kms_getfb (getfb-handle-protection)
[363.496123] Starting subtest: getfb-handle-protection
[363.508946] Subtest getfb-handle-protection: SUCCESS (0.013s)
[363.631204] [077/133] (657s left) kms_addfb_basic (bad-pitch-128)
[363.683061] Starting subtest: bad-pitch-128
[363.685283] Subtest bad-pitch-128: SUCCESS (0.000s)
[363.814176] [078/133] (657s left) gem_partial_pwrite_pread (writes-after-reads-display)
[363.871315] Subtest writes-after-reads-display: SKIP (0.000s)
[363.941080] [079/133] (657s left) kms_content_protection (atomic)
[364.084298] Starting subtest: atomic
[364.088616] Subtest atomic: SKIP (0.000s)
[364.208753] [080/133] (656s left) kms_cursor_edge_walk (256x256-left-edge)
[364.305378] Starting subtest: 256x256-left-edge
[364.307334] Starting dynamic subtest: pipe-A-eDP-1
[367.747214] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.439s)
[367.759766] Starting dynamic subtest: pipe-D-eDP-1
[370.860391] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.113s)
[370.862554] Subtest 256x256-left-edge: SUCCESS (6.555s)
[371.674721] [081/133] (649s left) kms_pm_rpm (cursor)
[373.419063] Starting subtest: cursor
[373.424168] Subtest cursor: SKIP (0.000s)
[373.917997] [082/133] (647s left) kms_atomic (plane-invalid-params-fence)
[374.021025] Starting subtest: plane-invalid-params-fence
[374.023275] Starting dynamic subtest: pipe-A-eDP-1
[374.381778] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.361s)
[374.384019] Subtest plane-invalid-params-fence: SUCCESS (0.363s)
[375.240906] [083/133] (646s left) kms_async_flips (crc)
[375.329605] Starting subtest: crc
[375.712584] Starting dynamic subtest: pipe-A-eDP-1
[378.666276] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.952s)
[379.049359] Starting dynamic subtest: pipe-B-eDP-1
[382.003646] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.963s)
[382.376834] Starting dynamic subtest: pipe-C-eDP-1
[385.301068] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.933s)
[385.682586] Starting dynamic subtest: pipe-D-eDP-1
[388.541200] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.871s)
[388.543526] Subtest crc: SUCCESS (13.211s)
[389.718435] [084/133] (631s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-render)
[389.834353] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-render
[389.838502] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[389.944006] [085/133] (631s left) kms_plane_cursor (viewport)
[390.037434] Starting subtest: viewport
[390.041864] Starting dynamic subtest: pipe-A-eDP-1-size-64
[392.727460] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.688s)
[392.787209] Starting dynamic subtest: pipe-A-eDP-1-size-128
[395.364306] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.582s)
[395.425994] Starting dynamic subtest: pipe-A-eDP-1-size-256
[398.011807] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.593s)
[398.359969] Starting dynamic subtest: pipe-B-eDP-1-size-64
[401.633762] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.282s)
[401.695488] Starting dynamic subtest: pipe-B-eDP-1-size-128
[404.314441] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.626s)
[404.374786] Starting dynamic subtest: pipe-B-eDP-1-size-256
[406.960547] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.596s)
[407.328510] Starting dynamic subtest: pipe-C-eDP-1-size-64
[410.294509] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.975s)
[410.333516] Starting dynamic subtest: pipe-C-eDP-1-size-128
[412.540848] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.215s)
[412.584641] Starting dynamic subtest: pipe-C-eDP-1-size-256
[414.785386] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.215s)
[415.128441] Starting dynamic subtest: pipe-D-eDP-1-size-64
[417.999657] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.882s)
[418.039083] Starting dynamic subtest: pipe-D-eDP-1-size-128
[420.279548] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.247s)
[420.316088] Starting dynamic subtest: pipe-D-eDP-1-size-256
[422.533256] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.225s)
[422.852421] Subtest viewport: SUCCESS (32.802s)
[423.679909] [086/133] (597s left) i915_pm_rps (thresholds-idle)
[423.743629] Starting subtest: thresholds-idle
[423.748140] Subtest thresholds-idle: SKIP (0.001s)
[423.850134] [087/133] (597s left) kms_addfb_basic (bo-too-small)
[423.957664] Starting subtest: bo-too-small
[423.959570] Subtest bo-too-small: SUCCESS (0.000s)
[424.051832] [088/133] (597s left) gem_exec_whisper (basic-queues-forked)
[424.127175] Starting subtest: basic-queues-forked
[447.418947] Subtest basic-queues-forked: SUCCESS (23.288s)
[447.602750] [089/133] (573s left) perf (invalid-oa-format-id)
[447.703485] Starting subtest: invalid-oa-format-id
[447.716345] Subtest invalid-oa-format-id: SUCCESS (0.011s)
[447.759861] [090/133] (573s left) kms_psr2_sf (psr2-cursor-plane-update-sf)
[447.862321] Starting subtest: psr2-cursor-plane-update-sf
[447.864590] Starting dynamic subtest: pipe-A-eDP-1
[448.423760] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.564s)
[448.435477] Starting dynamic subtest: pipe-B-eDP-1
[449.755536] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.330s)
[449.757814] Subtest psr2-cursor-plane-update-sf: SUCCESS (1.898s)
[450.632941] [091/133] (571s left) gem_exec_reloc (basic-cpu-gtt)
[450.698731] Subtest basic-cpu-gtt: SKIP (0.000s)
[450.775851] [092/133] (570s left) kms_ccs (missing-ccs-buffer-y-tiled-gen12-mc-ccs)
[450.874013] Starting subtest: missing-ccs-buffer-y-tiled-gen12-mc-ccs
[450.876284] Starting dynamic subtest: pipe-A-eDP-1
[450.900029] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[450.910841] Starting dynamic subtest: pipe-B-eDP-1
[450.932086] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[450.942045] Starting dynamic subtest: pipe-C-eDP-1
[450.963196] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[450.975470] Starting dynamic subtest: pipe-D-eDP-1
[450.996566] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[451.000477] Subtest missing-ccs-buffer-y-tiled-gen12-mc-ccs: SKIP (0.004s)
[453.106363] [093/133] (568s left) gem_close_race (multigpu-basic-process)
[453.177945] Starting subtest: multigpu-basic-process
[453.182304] Subtest multigpu-basic-process: SKIP (0.000s)
[453.410123] [094/133] (568s left) gen9_exec_parse (batch-invalid-length)
[453.475554] Subtest batch-invalid-length: SKIP (0.000s)
[453.555430] [095/133] (568s left) gem_exec_fence (syncobj-unused-fence)
[453.992069] Starting subtest: syncobj-unused-fence
[453.997945] Subtest syncobj-unused-fence: SUCCESS (0.005s)
[455.328005] [096/133] (566s left) gem_exec_nop (basic-sequential)
[455.410236] Starting subtest: basic-sequential
[469.651969] Subtest basic-sequential: SUCCESS (14.251s)
[469.875110] [097/133] (552s left) kms_frontbuffer_tracking (fbc-2p-shrfb-fliptrack-mmap-gtt)
[469.979926] Starting subtest: fbc-2p-shrfb-fliptrack-mmap-gtt
[469.983983] Subtest fbc-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[470.086309] [098/133] (551s left) kms_big_fb (4-tiled-64bpp-rotate-90)
[470.198076] Starting subtest: 4-tiled-64bpp-rotate-90
[470.221359] Subtest 4-tiled-64bpp-rotate-90: SKIP (0.022s)
[470.345904] [099/133] (551s left) kms_pipe_b_c_ivb (from-pipe-c-to-b-with-3-lanes)
[470.418741] Subtest from-pipe-C-to-B-with-3-lanes: SKIP (0.000s)
[470.487944] [100/133] (551s left) kms_fence_pin_leak
[470.641522] [101/133] (551s left) prime_busy (hang-wait)
[470.699336] Starting subtest: hang-wait
[470.701645] Starting dynamic subtest: rcs0
[496.657730] Dynamic subtest rcs0: SUCCESS (25.952s)
[496.667433] Starting dynamic subtest: bcs0
[503.813722] Dynamic subtest bcs0: SUCCESS (7.154s)
[503.825684] Starting dynamic subtest: vcs0
[510.786193] Dynamic subtest vcs0: SUCCESS (6.973s)
[510.796116] Starting dynamic subtest: vcs1
[517.824658] Dynamic subtest vcs1: SUCCESS (7.033s)
[517.836520] Starting dynamic subtest: vecs0
[524.798412] Dynamic subtest vecs0: SUCCESS (6.973s)
[524.810160] Starting dynamic subtest: ccs0
[538.704712] Dynamic subtest ccs0: SUCCESS (13.903s)
[538.707161] Subtest hang-wait: SUCCESS (68.001s)
[538.835011] [102/133] (483s left) kms_chamelium_hpd (hdmi-hpd-for-each-pipe)
[539.229486] Subtest hdmi-hpd-for-each-pipe: SKIP (0.000s)
[540.018028] [103/133] (482s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-onoff)
[540.122055] Starting subtest: psr-2p-primscrn-spr-indfb-onoff
[540.126057] Subtest psr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[540.221269] [104/133] (482s left) gem_unref_active_buffers
[540.430663] [105/133] (481s left) kms_rmfb (rmfb-ioctl)
[540.524755] Starting subtest: rmfb-ioctl
[540.526957] Starting dynamic subtest: pipe-A-eDP-1
[540.630284] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.107s)
[540.646698] Starting dynamic subtest: pipe-B-eDP-1
[541.789087] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.156s)
[541.800787] Starting dynamic subtest: pipe-C-eDP-1
[542.920786] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.130s)
[542.935999] Starting dynamic subtest: pipe-D-eDP-1
[544.056824] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.134s)
[544.059100] Subtest rmfb-ioctl: SUCCESS (3.534s)
[545.195946] [106/133] (477s left) gem_exec_reloc (basic-cpu-read-noreloc)
[545.263749] Subtest basic-cpu-read-noreloc: SKIP (0.000s)
[545.337829] [107/133] (477s left) kms_cursor_crc (cursor-dpms)
[545.470308] Starting subtest: cursor-dpms
[545.472593] Starting dynamic subtest: pipe-A-eDP-1
[548.471990] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.000s)
[548.484954] Starting dynamic subtest: pipe-D-eDP-1
[552.055406] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.580s)
[552.057303] Subtest cursor-dpms: SUCCESS (6.583s)
[552.906148] [108/133] (469s left) kms_debugfs (display-off-read-all)
[553.011809] Starting subtest: display-off-read-all
[553.305075] Subtest display-off-read-all: SUCCESS (0.295s)
[554.127535] [109/133] (468s left) perf_pmu (most-busy-idle-check-all)
[554.175644] Starting subtest: most-busy-idle-check-all
[554.177543] Starting dynamic subtest: rcs0
[556.210439] Dynamic subtest rcs0: SUCCESS (2.034s)
[556.223865] Starting dynamic subtest: bcs0
[558.195005] Dynamic subtest bcs0: SUCCESS (1.981s)
[558.207550] Starting dynamic subtest: vcs0
[560.177548] Dynamic subtest vcs0: SUCCESS (1.979s)
[560.190311] Starting dynamic subtest: vcs1
[562.225678] Dynamic subtest vcs1: SUCCESS (2.045s)
[562.238342] Starting dynamic subtest: vecs0
[564.210129] Dynamic subtest vecs0: SUCCESS (1.981s)
[564.223023] Starting dynamic subtest: ccs0
[566.193288] Dynamic subtest ccs0: SUCCESS (1.980s)
[566.195943] Subtest most-busy-idle-check-all: SUCCESS (12.018s)
[566.304162] [110/133] (456s left) gem_exec_balancer (waits)
[566.382560] Starting subtest: waits
[572.909004] Subtest waits: SUCCESS (6.522s)
[573.208384] [111/133] (449s left) kms_ccs (crc-primary-basic-4-tiled-mtl-mc-ccs)
[573.322823] Starting subtest: crc-primary-basic-4-tiled-mtl-mc-ccs
[573.325095] Starting dynamic subtest: pipe-A-eDP-1
[575.599545] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.277s)
[575.609951] Starting dynamic subtest: pipe-B-eDP-1
[578.011018] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.410s)
[578.021101] Starting dynamic subtest: pipe-C-eDP-1
[580.207826] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.196s)
[580.217934] Starting dynamic subtest: pipe-D-eDP-1
[582.251853] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.041s)
[582.253945] Subtest crc-primary-basic-4-tiled-mtl-mc-ccs: SUCCESS (8.929s)
[583.491836] [112/133] (439s left) perf (invalid-oa-metric-set-id)
[583.579523] Starting subtest: invalid-oa-metric-set-id
[583.590301] Subtest invalid-oa-metric-set-id: SUCCESS (0.008s)
[583.631651] [113/133] (438s left) kms_fbcon_fbt (fbc)
[583.703557] Starting subtest: fbc
[590.024647] Subtest fbc: SUCCESS (6.321s)
[591.103627] [114/133] (431s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-msflip-blt)
[591.196322] Starting subtest: fbc-2p-scndscrn-indfb-msflip-blt
[591.200456] Subtest fbc-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[591.306596] [115/133] (431s left) kms_lease (lease-again)
[591.389842] Starting subtest: lease-again
[591.391997] Starting dynamic subtest: pipe-A-eDP-1
[591.394067] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.003s)
[591.405049] Starting dynamic subtest: pipe-B-eDP-1
[591.407158] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.002s)
[591.417097] Starting dynamic subtest: pipe-C-eDP-1
[591.418938] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.002s)
[591.428671] Starting dynamic subtest: pipe-D-eDP-1
[591.430479] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.002s)
[591.432376] Subtest lease-again: SUCCESS (0.015s)
[591.498947] [116/133] (431s left) kms_chamelium_color (ctm-max)
[591.589333] Subtest ctm-max: SKIP (0.000s)
[591.635397] [117/133] (431s left) gem_softpin (zero)
[591.698860] Starting subtest: zero
[591.703263] Subtest zero: SUCCESS (0.004s)
[591.812973] [118/133] (431s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[591.910495] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[591.915000] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[592.018951] [119/133] (430s left) perf (missing-sample-flags)
[592.103122] Starting subtest: missing-sample-flags
[592.104683] Subtest missing-sample-flags: SUCCESS (0.000s)
[592.147633] [120/133] (430s left) core_auth (getclient-simple)
[592.185157] Starting subtest: getclient-simple
[592.206592] Subtest getclient-simple: SUCCESS (0.020s)
[592.300024] [121/133] (430s left) kms_color (degamma)
[592.397705] Starting subtest: degamma
[592.399659] Starting dynamic subtest: pipe-A-eDP-1
[593.131901] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.730s)
[593.142947] Starting dynamic subtest: pipe-B-eDP-1
[594.546942] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.415s)
[594.558088] Starting dynamic subtest: pipe-C-eDP-1
[595.918292] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.371s)
[595.929356] Starting dynamic subtest: pipe-D-eDP-1
[597.255628] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.336s)
[597.257538] Subtest degamma: SUCCESS (4.855s)
[598.055130] [122/133] (424s left) kms_frontbuffer_tracking (fbcpsr-2p-pri-indfb-multidraw)
[598.151436] Starting subtest: fbcpsr-2p-pri-indfb-multidraw
[598.155763] Subtest fbcpsr-2p-pri-indfb-multidraw: SKIP (0.000s)
[598.274331] [123/133] (424s left) kms_ccs (bad-rotation-90-4-tiled-bmg-ccs)
[598.369646] Starting subtest: bad-rotation-90-4-tiled-bmg-ccs
[598.374214] Subtest bad-rotation-90-4-tiled-bmg-ccs: SKIP (0.000s)
[599.489233] [124/133] (423s left) gem_ctx_create (basic-files)
[599.551490] Starting subtest: basic-files
[601.559353] Subtest basic-files: SUCCESS (2.008s)
[603.233012] [125/133] (419s left) kms_cursor_crc (cursor-rapid-movement-256x256)
[603.508842] Starting subtest: cursor-rapid-movement-256x256
[603.513862] Starting dynamic subtest: pipe-A-eDP-1
[604.054196] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.539s)
[604.085301] Starting dynamic subtest: pipe-D-eDP-1
[605.321118] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.246s)
[605.323541] Subtest cursor-rapid-movement-256x256: SUCCESS (1.812s)
[606.196560] [126/133] (416s left) kms_chamelium_frames (dp-frame-dump)
[606.593800] Subtest dp-frame-dump: SKIP (0.000s)
[607.376918] [127/133] (415s left) gem_mmap_gtt (medium-copy)
[607.431403] Subtest medium-copy: SKIP (0.000s)
[607.495725] [128/133] (415s left) gem_ctx_param (invalid-param-set)
[607.555899] Starting subtest: invalid-param-set
[607.558292] Subtest invalid-param-set: SUCCESS (0.000s)
[607.645271] [129/133] (415s left) kms_psr (fbc-psr2-sprite-plane-move)
[607.738386] Starting subtest: fbc-psr2-sprite-plane-move
[607.740736] Starting dynamic subtest: eDP-1
[607.745257] Dynamic subtest eDP-1: SKIP (0.000s)
[607.749262] Subtest fbc-psr2-sprite-plane-move: SKIP (0.002s)
[607.820679] [130/133] (415s left) gem_exec_balancer (full-pulse)
[607.888394] Starting subtest: full-pulse
[612.062486] Subtest full-pulse: SUCCESS (4.173s)
[612.390403] [131/133] (411s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-gtt)
[612.481617] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-gtt
[612.486057] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[612.575434] [132/133] (410s left) kms_cursor_legacy (flip-vs-cursor-crc-atomic)
[612.689171] Starting subtest: flip-vs-cursor-crc-atomic
[613.374524] Subtest flip-vs-cursor-crc-atomic: SUCCESS (0.687s)
[614.218509] [133/133] (409s left) kms_vblank (invalid)
[614.308434] Starting subtest: invalid
[614.310347] Starting dynamic subtest: pipe-A-eDP-1
[614.704982] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.396s)
[614.707150] Subtest invalid: SUCCESS (0.397s)
[615.541195] Closing watchdogs
results: parsing output: 0/ for test: i915_drm_fdinfo
results: parsing output: 1/ for test: kms_hdr
results: parsing output: 2/ for test: gem_ctx_param
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: gem_render_copy
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: i915_pm_rps
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: kms_rotation_crc
results: parsing output: 10/ for test: syncobj_wait
results: parsing output: 11/ for test: syncobj_timeline
results: parsing output: 12/ for test: kms_psr
results: parsing output: 13/ for test: kms_vblank
results: parsing output: 14/ for test: syncobj_timeline
results: parsing output: 15/ for test: gem_mmap_gtt
results: parsing output: 16/ for test: gem_ctx_param
results: parsing output: 17/ for test: gem_tiled_fence_blits
results: parsing output: 18/ for test: gem_linear_blits
results: parsing output: 19/ for test: kms_big_fb
results: parsing output: 20/ for test: kms_plane_scaling
results: parsing output: 21/ for test: kms_pm_rpm
results: parsing output: 22/ for test: syncobj_timeline
results: parsing output: 23/ for test: kms_flip_scaled_crc
results: parsing output: 24/ for test: gem_ctx_param
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_big_fb
results: parsing output: 27/ for test: gem_ctx_persistence
results: parsing output: 28/ for test: kms_flip_scaled_crc
results: parsing output: 29/ for test: kms_plane_scaling
results: parsing output: 30/ for test: kms_flip
results: parsing output: 31/ for test: gem_exec_parallel
results: parsing output: 32/ for test: gem_exec_alignment
results: parsing output: 33/ for test: kms_psr
results: parsing output: 34/ for test: kms_vrr
results: parsing output: 35/ for test: i915_module_load
results: parsing output: 36/ for test: gem_vm_create
results: parsing output: 37/ for test: kms_ccs
results: parsing output: 38/ for test: kms_dsc
results: parsing output: 39/ for test: kms_flip
results: parsing output: 40/ for test: gem_exec_schedule
results: parsing output: 41/ for test: gem_exec_schedule
results: parsing output: 42/ for test: gem_close
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: kms_plane_alpha_blend
results: parsing output: 45/ for test: kms_psr
results: parsing output: 46/ for test: kms_big_fb
results: parsing output: 47/ for test: dmabuf_sync_file
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: gem_mmap_gtt
results: parsing output: 50/ for test: gem_exec_fence
results: parsing output: 51/ for test: gem_userptr_blits
results: parsing output: 52/ for test: kms_big_fb
results: parsing output: 53/ for test: kms_busy
results: parsing output: 54/ for test: gem_readwrite
results: parsing output: 55/ for test: gem_exec_whisper
results: parsing output: 56/ for test: gem_mmap_offset
results: parsing output: 57/ for test: kms_plane
results: parsing output: 58/ for test: api_intel_allocator
results: parsing output: 59/ for test: gem_mmap
results: parsing output: 60/ for test: syncobj_timeline
results: parsing output: 61/ for test: i915_query
results: parsing output: 62/ for test: syncobj_timeline
results: parsing output: 63/ for test: gem_render_copy
results: parsing output: 64/ for test: kms_flip
results: parsing output: 65/ for test: gem_create
results: parsing output: 66/ for test: gem_barrier_race
results: parsing output: 67/ for test: kms_prop_blob
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: gem_mmap_gtt
results: parsing output: 70/ for test: kms_flip
results: parsing output: 71/ for test: kms_psr
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: syncobj_timeline
results: parsing output: 74/ for test: kms_psr
results: parsing output: 75/ for test: kms_getfb
results: parsing output: 76/ for test: kms_addfb_basic
results: parsing output: 77/ for test: gem_partial_pwrite_pread
results: parsing output: 78/ for test: kms_content_protection
results: parsing output: 79/ for test: kms_cursor_edge_walk
results: parsing output: 80/ for test: kms_pm_rpm
results: parsing output: 81/ for test: kms_atomic
results: parsing output: 82/ for test: kms_async_flips
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: kms_plane_cursor
results: parsing output: 85/ for test: i915_pm_rps
results: parsing output: 86/ for test: kms_addfb_basic
results: parsing output: 87/ for test: gem_exec_whisper
results: parsing output: 88/ for test: perf
results: parsing output: 89/ for test: kms_psr2_sf
results: parsing output: 90/ for test: gem_exec_reloc
results: parsing output: 91/ for test: kms_ccs
results: parsing output: 92/ for test: gem_close_race
results: parsing output: 93/ for test: gen9_exec_parse
results: parsing output: 94/ for test: gem_exec_fence
results: parsing output: 95/ for test: gem_exec_nop
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: kms_big_fb
results: parsing output: 98/ for test: kms_pipe_b_c_ivb
results: parsing output: 99/ for test: kms_fence_pin_leak
results: parsing output: 100/ for test: prime_busy
results: parsing output: 101/ for test: kms_chamelium_hpd
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: gem_unref_active_buffers
results: parsing output: 104/ for test: kms_rmfb
results: parsing output: 105/ for test: gem_exec_reloc
results: parsing output: 106/ for test: kms_cursor_crc
results: parsing output: 107/ for test: kms_debugfs
results: parsing output: 108/ for test: perf_pmu
results: parsing output: 109/ for test: gem_exec_balancer
results: parsing output: 110/ for test: kms_ccs
results: parsing output: 111/ for test: perf
results: parsing output: 112/ for test: kms_fbcon_fbt
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: kms_lease
results: parsing output: 115/ for test: kms_chamelium_color
results: parsing output: 116/ for test: gem_softpin
results: parsing output: 117/ for test: kms_big_fb
results: parsing output: 118/ for test: perf
results: parsing output: 119/ for test: core_auth
results: parsing output: 120/ for test: kms_color
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: kms_ccs
results: parsing output: 123/ for test: gem_ctx_create
results: parsing output: 124/ for test: kms_cursor_crc
results: parsing output: 125/ for test: kms_chamelium_frames
results: parsing output: 126/ for test: gem_mmap_gtt
results: parsing output: 127/ for test: gem_ctx_param
results: parsing output: 128/ for test: kms_psr
results: parsing output: 129/ for test: gem_exec_balancer
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: kms_cursor_legacy
results: parsing output: 132/ for test: kms_vblank
Done.