[58.994316] Initializing watchdogs
[58.994363]   /dev/watchdog0
[59.011998] [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]
[59.016885] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.021372] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.024844] [001/133] (960s left) i915_pm_freq_api (freq-reset)
[59.114794] Starting subtest: freq-reset
[59.117269] Starting dynamic subtest: gt0
[59.267811] Dynamic subtest gt0: SUCCESS (0.152s)
[59.276876] Starting dynamic subtest: gt1
[59.398032] Dynamic subtest gt1: SUCCESS (0.129s)
[59.400887] Subtest freq-reset: SUCCESS (0.282s)
[59.472621] [002/133] (960s left) syncobj_wait (invalid-signal-bad-pad)
[59.528564] Starting subtest: invalid-signal-bad-pad
[59.530991] Subtest invalid-signal-bad-pad: SUCCESS (0.000s)
[59.637308] [003/133] (959s left) sw_sync (sync_merge_invalid)
[59.686551] Starting subtest: sync_merge_invalid
[59.718571] Subtest sync_merge_invalid: SUCCESS (0.033s)
[59.795380] [004/133] (959s left) kms_ccs (missing-ccs-buffer-y-tiled-gen12-rc-ccs)
[59.897339] Starting subtest: missing-ccs-buffer-y-tiled-gen12-rc-ccs
[59.899669] Starting dynamic subtest: pipe-A-eDP-1
[59.922536] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[59.933051] Starting dynamic subtest: pipe-B-eDP-1
[59.954367] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[59.962287] Starting dynamic subtest: pipe-C-eDP-1
[59.984823] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[59.993758] Starting dynamic subtest: pipe-D-eDP-1
[60.015748] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[60.019809] Subtest missing-ccs-buffer-y-tiled-gen12-rc-ccs: SKIP (0.003s)
[62.122845] [005/133] (957s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-pwrite)
[62.222728] Starting subtest: psr-2p-primscrn-spr-indfb-draw-pwrite
[62.227227] Subtest psr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[62.324229] [006/133] (957s left) prime_vgem (basic-fence-mmap)
[62.430103] Starting subtest: basic-fence-mmap
[62.435586] Subtest basic-fence-mmap: SKIP (0.000s)
[62.539673] [FACT prime_vgem (basic-fence-mmap)] new: kernel.kmod_is_loaded.vgem: true
[62.543491] [007/133] (957s left) gem_pxp (reject-modify-context-protection-off-3)
[62.660389] Starting subtest: reject-modify-context-protection-off-3
[62.662981] Subtest reject-modify-context-protection-off-3: SUCCESS (0.002s)
[62.763086] [008/133] (957s left) kms_psr (fbc-psr-sprite-plane-move)
[62.861058] Starting subtest: fbc-psr-sprite-plane-move
[62.863484] Starting dynamic subtest: eDP-1
[62.867990] Dynamic subtest eDP-1: SKIP (0.000s)
[62.872537] Subtest fbc-psr-sprite-plane-move: SKIP (0.000s)
[62.932718] [009/133] (956s left) perf (enable-disable)
[63.043812] Starting subtest: enable-disable
[63.046030] Starting dynamic subtest: 0-rcs0
[65.315102] Dynamic subtest 0-rcs0: SUCCESS (2.270s)
[65.324077] Starting dynamic subtest: 1-vecs0
[69.792874] Dynamic subtest 1-vecs0: SUCCESS (4.477s)
[69.795377] Subtest enable-disable: SUCCESS (6.749s)
[69.844960] [010/133] (950s left) i915_pm_rps (thresholds-park)
[69.899728] Starting subtest: thresholds-park
[69.904528] Subtest thresholds-park: SKIP (0.000s)
[70.005416] [011/133] (949s left) gen9_exec_parse (unaligned-access)
[70.074712] Subtest unaligned-access: SKIP (0.000s)
[70.141665] [012/133] (949s left) gem_exercise_blt (fast-copy)
[70.198045] Starting subtest: fast-copy
[70.200218] Starting dynamic subtest: linear-smem-smem
[70.233803] Dynamic subtest linear-smem-smem: SUCCESS (0.037s)
[70.243399] Starting dynamic subtest: xmajor-smem-smem
[70.253212] Dynamic subtest xmajor-smem-smem: SUCCESS (0.020s)
[70.260971] Starting dynamic subtest: tile4-smem-smem
[70.273256] Dynamic subtest tile4-smem-smem: SUCCESS (0.020s)
[70.281613] Starting dynamic subtest: tile64-smem-smem
[70.292582] Dynamic subtest tile64-smem-smem: SUCCESS (0.019s)
[70.294825] Subtest fast-copy: SUCCESS (0.097s)
[70.407882] [013/133] (949s left) gem_userptr_blits (map-fixed-invalidate-overlap)
[70.488181] Starting subtest: map-fixed-invalidate-overlap
[70.492321] Subtest map-fixed-invalidate-overlap: SKIP (0.000s)
[70.620162] [014/133] (949s left) gen9_exec_parse (bb-start-far)
[70.671622] Subtest bb-start-far: SKIP (0.000s)
[70.740155] [015/133] (949s left) gem_exec_whisper (basic-contexts-priority-all)
[70.802145] Starting subtest: basic-contexts-priority-all
[95.291968] Subtest basic-contexts-priority-all: SUCCESS (24.434s)
[95.423943] [016/133] (924s left) gem_fence_thrash (bo-write-verify-threaded-none)
[95.472452] Starting subtest: bo-write-verify-threaded-none
[95.496800] Subtest bo-write-verify-threaded-none: SKIP (0.023s)
[95.553595] [017/133] (924s left) gem_tiled_pread_basic (basic)
[95.618052] Subtest basic: SKIP (0.000s)
[95.673650] [018/133] (924s left) kms_vblank (wait-idle)
[95.767695] Starting subtest: wait-idle
[95.770306] Starting dynamic subtest: pipe-A-eDP-1
[97.514874] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.746s)
[97.529168] Starting dynamic subtest: pipe-D-eDP-1
[99.969931] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.453s)
[99.972307] Subtest wait-idle: SUCCESS (4.202s)
[100.792738] [019/133] (919s left) api_intel_bb (render)
[100.842920] Starting subtest: render
[100.845110] Starting dynamic subtest: render-none-512
[100.941931] Dynamic subtest render-none-512: SUCCESS (0.101s)
[100.952047] Starting dynamic subtest: render-none-1024
[101.345352] Dynamic subtest render-none-1024: SUCCESS (0.401s)
[101.355854] Starting dynamic subtest: render-x-512
[101.430311] Dynamic subtest render-x-512: SUCCESS (0.086s)
[101.440870] Starting dynamic subtest: render-x-1024
[101.773476] Dynamic subtest render-x-1024: SUCCESS (0.342s)
[101.784040] Starting dynamic subtest: render-y-512
[101.857620] Dynamic subtest render-y-512: SUCCESS (0.085s)
[101.868212] Starting dynamic subtest: render-y-1024
[102.201462] Dynamic subtest render-y-1024: SUCCESS (0.342s)
[102.204235] Subtest render: SUCCESS (1.359s)
[102.345181] [020/133] (917s left) kms_addfb_basic (invalid-smem-bo-on-discrete)
[102.391855] Starting subtest: invalid-smem-bo-on-discrete
[102.396495] Subtest invalid-smem-bo-on-discrete: SKIP (0.000s)
[102.529824] [021/133] (917s left) kms_content_protection (atomic)
[102.674792] Starting subtest: atomic
[102.679394] Subtest atomic: SKIP (0.000s)
[102.785567] [022/133] (917s left) kms_cursor_legacy (short-flip-after-cursor-toggle)
[102.901115] Starting subtest: short-flip-after-cursor-toggle
[103.852206] Subtest short-flip-after-cursor-toggle: SUCCESS (0.949s)
[104.708728] [023/133] (915s left) gem_exec_params (larger-than-life-batch)
[104.775752] Starting subtest: larger-than-life-batch
[107.231936] Subtest larger-than-life-batch: SUCCESS (2.456s)
[108.349059] [024/133] (912s left) gem_exec_suspend (basic-s4-devices)
[108.474606] Starting subtest: basic-S4-devices
[108.477015] Starting dynamic subtest: smem
[121.279049] Dynamic subtest smem: SUCCESS (12.806s)
[121.283065] Subtest basic-S4-devices: SUCCESS (12.807s)
[128.494437] [025/133] (899s left) drm_mm (drm_mm)
[128.567213] Starting subtest: drm_mm
[128.622061] Starting dynamic subtest: drm_test_mm_init
[128.709254] Dynamic subtest drm_test_mm_init: SUCCESS (0.086s)
[128.721771] Starting dynamic subtest: drm_test_mm_debug
[128.797181] Dynamic subtest drm_test_mm_debug: SUCCESS (0.087s)
[128.807171] Starting dynamic subtest: drm_test_mm_align32
[128.887405] Dynamic subtest drm_test_mm_align32: SUCCESS (0.090s)
[128.897620] Starting dynamic subtest: drm_test_mm_align64
[128.973194] Dynamic subtest drm_test_mm_align64: SUCCESS (0.085s)
[128.982882] Starting dynamic subtest: drm_test_mm_lowest
[129.068373] Dynamic subtest drm_test_mm_lowest: SUCCESS (0.094s)
[129.080067] Starting dynamic subtest: drm_test_mm_highest
[129.170686] Dynamic subtest drm_test_mm_highest: SUCCESS (0.102s)
[129.172994] Subtest drm_mm: SUCCESS (0.604s)
[129.361123] [026/133] (898s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-upscaling)
[129.471102] Starting subtest: flip-32bpp-linear-to-64bpp-linear-upscaling
[129.473532] Starting dynamic subtest: pipe-A-default-mode
[132.255508] Dynamic subtest pipe-A-default-mode: SUCCESS (2.783s)
[132.257881] Subtest flip-32bpp-linear-to-64bpp-linear-upscaling: SUCCESS (2.785s)
[133.085043] [027/133] (894s left) gem_exec_balancer (hang)
[133.347772] Starting subtest: hang
[135.288287] Subtest hang: SUCCESS (1.943s)
[135.407052] [028/133] (892s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs-cc)
[135.505748] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs-cc
[135.508042] Starting dynamic subtest: pipe-A-eDP-1
[135.527738] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[135.540217] Starting dynamic subtest: pipe-B-eDP-1
[135.558803] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[135.571700] Starting dynamic subtest: pipe-C-eDP-1
[135.591065] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[135.601537] Starting dynamic subtest: pipe-D-eDP-1
[135.621261] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[135.623528] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.004s)
[137.745684] [029/133] (889s left) gem_ctx_isolation (preservation-reset)
[137.909074] Starting subtest: preservation-reset
[137.911952] Starting dynamic subtest: rcs0
[138.191591] Dynamic subtest rcs0: SUCCESS (0.283s)
[138.206902] Starting dynamic subtest: bcs0
[138.531528] Dynamic subtest bcs0: SUCCESS (0.339s)
[138.543032] Starting dynamic subtest: vcs0
[138.858727] Dynamic subtest vcs0: SUCCESS (0.327s)
[138.870605] Starting dynamic subtest: vcs1
[140.598029] Dynamic subtest vcs1: SUCCESS (1.739s)
[140.613938] Starting dynamic subtest: vecs0
[142.585762] Dynamic subtest vecs0: SUCCESS (1.987s)
[142.599042] Starting dynamic subtest: ccs0
[144.604033] Dynamic subtest ccs0: SUCCESS (2.018s)
[144.606578] Subtest preservation-reset: SUCCESS (6.697s)
[144.739405] [030/133] (882s left) gem_mmap (basic-small-bo)
[144.787074] Subtest basic-small-bo: SKIP (0.000s)
[144.844706] [031/133] (882s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[144.945149] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip
[144.949329] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[145.037503] [032/133] (882s left) syncobj_eventfd (invalid-illegal-handle)
[145.101447] Starting subtest: invalid-illegal-handle
[145.103832] Subtest invalid-illegal-handle: SUCCESS (0.000s)
[145.194945] [033/133] (882s left) kms_rmfb (rmfb-ioctl)
[145.288127] Starting subtest: rmfb-ioctl
[145.290101] Starting dynamic subtest: pipe-A-eDP-1
[145.391497] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.105s)
[145.405986] Starting dynamic subtest: pipe-B-eDP-1
[146.601168] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.208s)
[146.614278] Starting dynamic subtest: pipe-C-eDP-1
[147.740850] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.138s)
[147.752240] Starting dynamic subtest: pipe-D-eDP-1
[148.870378] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.130s)
[148.872858] Subtest rmfb-ioctl: SUCCESS (3.585s)
[150.013366] [034/133] (877s left) kms_cursor_crc (cursor-offscreen-max-size)
[150.331587] Starting subtest: cursor-offscreen-max-size
[150.338125] Subtest cursor-offscreen-max-size: SKIP (0.000s)
[150.473636] [035/133] (877s left) kms_frontbuffer_tracking (fbcpsr-shrfb-scaledprimary)
[150.580858] Starting subtest: fbcpsr-shrfb-scaledprimary
[156.404672] Subtest fbcpsr-shrfb-scaledprimary: SUCCESS (5.825s)
[156.533638] [036/133] (871s left) kms_universal_plane (disable-primary-vs-flip)
[156.634358] Starting subtest: disable-primary-vs-flip
[156.636788] Starting dynamic subtest: pipe-A-eDP-1
[158.216238] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.584s)
[158.228953] Starting dynamic subtest: pipe-B-eDP-1
[160.491693] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.273s)
[160.503740] Starting dynamic subtest: pipe-C-eDP-1
[162.744410] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.251s)
[162.756774] Starting dynamic subtest: pipe-D-eDP-1
[164.896805] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.151s)
[164.899341] Subtest disable-primary-vs-flip: SUCCESS (8.264s)
[165.702708] [037/133] (862s left) gem_spin_batch (legacy-resubmit)
[165.770620] Starting subtest: legacy-resubmit
[165.772424] Starting dynamic subtest: default
[165.776862] Dynamic subtest default: SUCCESS (0.006s)
[165.790231] Starting dynamic subtest: render
[165.792463] Dynamic subtest render: SUCCESS (0.001s)
[165.802895] Starting dynamic subtest: bsd
[165.805100] Dynamic subtest bsd: SUCCESS (0.002s)
[165.815179] Starting dynamic subtest: bsd1
[165.817247] Dynamic subtest bsd1: SUCCESS (0.001s)
[165.827449] Starting dynamic subtest: bsd2
[165.829633] Dynamic subtest bsd2: SUCCESS (0.003s)
[165.839779] Starting dynamic subtest: blt
[165.841959] Dynamic subtest blt: SUCCESS (0.002s)
[165.852457] Starting dynamic subtest: vebox
[165.854655] Dynamic subtest vebox: SUCCESS (0.003s)
[165.856818] Subtest legacy-resubmit: SUCCESS (0.023s)
[166.022774] [038/133] (862s left) kms_flip (2x-wf_vblank-ts-check-interruptible)
[166.121197] Starting subtest: 2x-wf_vblank-ts-check-interruptible
[166.132215] Subtest 2x-wf_vblank-ts-check-interruptible: SKIP (0.011s)
[166.251203] [039/133] (861s left) prime_busy (before)
[166.312830] Starting subtest: before
[166.318071] Starting dynamic subtest: rcs0
[166.350567] Dynamic subtest rcs0: SUCCESS (0.034s)
[166.363227] Starting dynamic subtest: bcs0
[166.391690] Dynamic subtest bcs0: SUCCESS (0.041s)
[166.402816] Starting dynamic subtest: vcs0
[166.432608] Dynamic subtest vcs0: SUCCESS (0.041s)
[166.443919] Starting dynamic subtest: vcs1
[166.473957] Dynamic subtest vcs1: SUCCESS (0.041s)
[166.485140] Starting dynamic subtest: vecs0
[166.514045] Dynamic subtest vecs0: SUCCESS (0.040s)
[166.525233] Starting dynamic subtest: ccs0
[166.554268] Dynamic subtest ccs0: SUCCESS (0.040s)
[166.662871] Subtest before: SUCCESS (0.347s)
[166.742589] [040/133] (861s left) kms_addfb_basic (clobberred-modifier)
[166.805459] Starting subtest: clobberred-modifier
[166.809938] Subtest clobberred-modifier: SKIP (0.000s)
[166.938136] [041/133] (861s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[167.034934] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu
[174.068181] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (7.038s)
[174.184720] [042/133] (854s left) gem_sync (basic-store-each)
[174.275183] Starting subtest: basic-store-each
[176.654860] Subtest basic-store-each: SUCCESS (2.391s)
[176.846757] [043/133] (851s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs)
[176.949338] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-rc-ccs
[176.951914] Starting dynamic subtest: pipe-A-eDP-1
[176.976265] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[176.989612] Starting dynamic subtest: pipe-B-eDP-1
[177.011465] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[177.021994] Starting dynamic subtest: pipe-C-eDP-1
[177.043614] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[177.053587] Starting dynamic subtest: pipe-D-eDP-1
[177.075480] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[177.079875] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs: SKIP (0.005s)
[179.190558] [044/133] (849s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[179.292150] Starting subtest: read-crc-frame-sequence
[179.294518] Starting dynamic subtest: pipe-A-eDP-1
[180.021691] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.729s)
[180.033708] Starting dynamic subtest: pipe-B-eDP-1
[181.375834] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.351s)
[181.387808] Starting dynamic subtest: pipe-C-eDP-1
[182.679352] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.303s)
[182.691668] Starting dynamic subtest: pipe-D-eDP-1
[184.013484] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.333s)
[184.015816] Subtest read-crc-frame-sequence: SUCCESS (4.721s)
[184.844751] [045/133] (843s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[184.939661] Starting subtest: fbcpsr-slowdraw
[210.161845] Subtest fbcpsr-slowdraw: SUCCESS (25.225s)
[210.314677] [046/133] (818s left) gem_ppgtt (shrink-vs-evict-pinned)
[210.390079] Starting subtest: shrink-vs-evict-pinned
[217.312579] Subtest shrink-vs-evict-pinned: SUCCESS (6.870s)
[217.381544] [047/133] (811s left) kms_psr (fbc-psr2-cursor-plane-onoff)
[217.480201] Starting subtest: fbc-psr2-cursor-plane-onoff
[217.482479] Starting dynamic subtest: eDP-1
[217.486575] Dynamic subtest eDP-1: SKIP (0.000s)
[217.490763] Subtest fbc-psr2-cursor-plane-onoff: SKIP (0.001s)
[217.570043] [048/133] (810s left) kms_big_fb (4-tiled-16bpp-rotate-180)
[217.668177] Starting subtest: 4-tiled-16bpp-rotate-180
[219.058253] Subtest 4-tiled-16bpp-rotate-180: SUCCESS (1.391s)
[219.197230] [049/133] (809s left) gem_mmap_gtt (fault-concurrent-y)
[219.256911] Subtest fault-concurrent-Y: SKIP (0.000s)
[219.326320] [050/133] (809s left) gem_exec_schedule (u-semaphore-user)
[219.389109] Starting subtest: u-semaphore-user
[219.443485] Subtest u-semaphore-user: SUCCESS (0.057s)
[221.368594] [051/133] (807s left) gem_create (create-ext-set-pat)
[221.543042] Starting subtest: create-ext-set-pat
[221.545615] Subtest create-ext-set-pat: SUCCESS (0.001s)
[221.660411] [052/133] (807s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-exceed-fully-sf)
[221.762065] Starting subtest: psr2-cursor-plane-move-continuous-exceed-fully-sf
[221.764380] Starting dynamic subtest: pipe-A-eDP-1
[232.738587] Dynamic subtest pipe-A-eDP-1: SUCCESS (10.979s)
[232.756445] Starting dynamic subtest: pipe-B-eDP-1
[244.477578] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.736s)
[244.479498] Subtest psr2-cursor-plane-move-continuous-exceed-fully-sf: SUCCESS (22.718s)
[245.327263] [053/133] (783s left) gem_fence_thrash (bo-copy)
[245.364870] Starting subtest: bo-copy
[245.392265] Subtest bo-copy: SKIP (0.027s)
[245.462233] [054/133] (783s left) perf (buffer-fill)
[245.578621] Starting subtest: buffer-fill
[245.580952] Starting dynamic subtest: 0-rcs0
[247.826380] Dynamic subtest 0-rcs0: SUCCESS (2.246s)
[247.840088] Starting dynamic subtest: 1-vcs1
[252.258114] Dynamic subtest 1-vcs1: SUCCESS (4.431s)
[252.260662] Subtest buffer-fill: SUCCESS (6.679s)
[252.307772] [055/133] (776s left) gem_userptr_blits (unsync-unmap-after-close)
[252.382478] Subtest unsync-unmap-after-close: SKIP (0.000s)
[252.499599] [056/133] (776s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-cc-modifier)
[252.598023] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-cc-modifier
[252.624108] Starting dynamic subtest: pipe-A-plane-0
[252.912591] Dynamic subtest pipe-A-plane-0: SUCCESS (0.289s)
[252.924446] Starting dynamic subtest: pipe-A-plane-3
[253.146133] Dynamic subtest pipe-A-plane-3: SUCCESS (0.233s)
[253.157569] Starting dynamic subtest: pipe-A-plane-5
[253.162309] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[254.213865] Starting dynamic subtest: pipe-B-plane-0
[254.501854] Dynamic subtest pipe-B-plane-0: SUCCESS (0.300s)
[254.518090] Starting dynamic subtest: pipe-B-plane-3
[254.735051] Dynamic subtest pipe-B-plane-3: SUCCESS (0.231s)
[254.747493] Starting dynamic subtest: pipe-B-plane-5
[254.752067] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[255.046314] Subtest pixel-format-4-tiled-mtl-rc-ccs-cc-modifier: SUCCESS (2.447s)
[255.877619] [057/133] (773s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[255.938813] Starting subtest: y-tiled-to-vebox-x-tiled
[255.943062] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[256.125493] [058/133] (772s left) kms_ccs (bad-aux-stride-4-tiled-mtl-rc-ccs-cc)
[256.215157] Starting subtest: bad-aux-stride-4-tiled-mtl-rc-ccs-cc
[256.217607] Starting dynamic subtest: pipe-A-eDP-1
[256.237841] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[256.251247] Starting dynamic subtest: pipe-B-eDP-1
[256.270526] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[256.282065] Starting dynamic subtest: pipe-C-eDP-1
[256.301791] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[256.313621] Starting dynamic subtest: pipe-D-eDP-1
[256.332845] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[256.335092] Subtest bad-aux-stride-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.008s)
[258.545554] [059/133] (770s left) kms_plane (pixel-format-4-tiled-bmg-ccs-modifier-source-clamping)
[258.636680] Starting subtest: pixel-format-4-tiled-bmg-ccs-modifier-source-clamping
[258.641314] Subtest pixel-format-4-tiled-bmg-ccs-modifier-source-clamping: SKIP (0.000s)
[258.714256] [060/133] (770s left) kms_psr (psr2-primary-render)
[258.802231] Starting subtest: psr2-primary-render
[258.804562] Starting dynamic subtest: eDP-1
[260.535593] Dynamic subtest eDP-1: SUCCESS (1.732s)
[260.537810] Subtest psr2-primary-render: SUCCESS (1.733s)
[261.413305] [061/133] (767s left) perf_pmu (busy-start)
[261.459259] Starting subtest: busy-start
[261.461494] Starting dynamic subtest: rcs0
[264.006654] Dynamic subtest rcs0: SUCCESS (2.549s)
[264.018584] Starting dynamic subtest: bcs0
[266.580143] Dynamic subtest bcs0: SUCCESS (2.550s)
[266.593049] Starting dynamic subtest: vcs0
[270.268089] Dynamic subtest vcs0: SUCCESS (3.706s)
[270.281912] Starting dynamic subtest: vcs1
[274.299965] Dynamic subtest vcs1: SUCCESS (4.030s)
[274.314076] Starting dynamic subtest: vecs0
[278.267939] Dynamic subtest vecs0: SUCCESS (3.966s)
[278.281047] Starting dynamic subtest: ccs0
[280.837606] Dynamic subtest ccs0: SUCCESS (2.549s)
[280.839754] Subtest busy-start: SUCCESS (19.362s)
[280.924364] [062/133] (748s left) perf (gen12-mi-rpc)
[281.015185] Starting subtest: gen12-mi-rpc
[281.017070] Starting dynamic subtest: rcs0
[281.032150] Dynamic subtest rcs0: SUCCESS (0.017s)
[281.034304] Subtest gen12-mi-rpc: SUCCESS (0.018s)
[281.076799] [063/133] (748s left) kms_chamelium_audio (dp-audio-edid)
[281.518595] Subtest dp-audio-edid: SKIP (0.000s)
[282.311153] [064/133] (746s left) i915_pm_rps (reset)
[282.363446] Starting subtest: reset
[290.812079] Subtest reset: SUCCESS (8.448s)
[290.919340] [065/133] (738s left) syncobj_timeline (invalid-multi-wait-unsubmitted-signaled)
[290.979949] Starting subtest: invalid-multi-wait-unsubmitted-signaled
[290.982208] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.001s)
[291.086455] [066/133] (738s left) kms_cursor_crc (cursor-random-64x64)
[291.284946] Starting subtest: cursor-random-64x64
[291.287226] Starting dynamic subtest: pipe-A-eDP-1
[294.651556] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.365s)
[294.684865] Starting dynamic subtest: pipe-D-eDP-1
[298.629509] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.954s)
[298.631999] Subtest cursor-random-64x64: SUCCESS (7.347s)
[299.477512] [067/133] (729s left) gem_mmap (short-mmap)
[299.538574] Subtest short-mmap: SKIP (0.000s)
[299.608981] [068/133] (729s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[299.706699] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[300.397583] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SUCCESS (0.693s)
[300.576425] [069/133] (728s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-fullscreen)
[300.683076] Starting subtest: fbc-2p-scndscrn-spr-indfb-fullscreen
[300.687216] Subtest fbc-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[300.784957] [070/133] (728s left) kms_joiner (basic-big-joiner)
[300.894304] Starting subtest: basic-big-joiner
[300.898626] Subtest basic-big-joiner: SKIP (0.000s)
[300.963785] [071/133] (728s left) gem_workarounds (suspend-resume-context)
[301.026593] Starting subtest: suspend-resume-context
[317.324806] Subtest suspend-resume-context: SUCCESS (1.213s)
[317.458056] [072/133] (712s left) gem_mmap_gtt (big-bo-tiledy)
[317.518077] Subtest big-bo-tiledY: SKIP (0.000s)
[317.588675] [073/133] (712s left) kms_psr (pr-cursor-plane-move)
[317.693695] Starting subtest: pr-cursor-plane-move
[317.698081] Subtest pr-cursor-plane-move: SKIP (0.000s)
[317.771049] [074/133] (711s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[317.863878] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt
[317.868205] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[317.967337] [075/133] (711s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[318.058515] Subtest addfb25-framebuffer-vs-set-tiling: SKIP (0.000s)
[318.156216] [076/133] (711s left) kms_cursor_crc (cursor-suspend)
[318.293187] Starting subtest: cursor-suspend
[318.295542] Starting dynamic subtest: pipe-A-eDP-1
[350.794926] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.174s)
[350.807315] Starting dynamic subtest: pipe-D-eDP-1
[384.760032] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.774s)
[384.762080] Subtest cursor-suspend: SUCCESS (6.951s)
[385.606268] [077/133] (644s left) gem_pread (uncached)
[385.688095] Subtest uncached: SKIP (0.000s)
[385.761738] [078/133] (644s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-render)
[385.861968] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-render
[389.636774] Subtest fbc-1p-primscrn-spr-indfb-draw-render: SUCCESS (3.778s)
[390.837193] [079/133] (639s left) kms_psr2_sf (psr2-overlay-plane-move-continuous-exceed-fully-sf)
[390.966884] Starting subtest: psr2-overlay-plane-move-continuous-exceed-fully-sf
[390.969259] Starting dynamic subtest: pipe-A-eDP-1
[407.416404] Dynamic subtest pipe-A-eDP-1: SUCCESS (16.455s)
[407.428152] Starting dynamic subtest: pipe-B-eDP-1
[424.585350] Dynamic subtest pipe-B-eDP-1: SUCCESS (17.165s)
[424.587343] Subtest psr2-overlay-plane-move-continuous-exceed-fully-sf: SUCCESS (33.623s)
[425.472638] [080/133] (604s left) gem_readwrite (read-bad-handle)
[425.544333] Subtest read-bad-handle: SKIP (0.000s)
[425.605915] [081/133] (604s left) api_intel_bb (bb-with-allocator)
[425.668806] Starting subtest: bb-with-allocator
[425.671670] Subtest bb-with-allocator: SUCCESS (0.006s)
[425.812038] [082/133] (604s left) kms_big_fb (x-tiled-32bpp-rotate-270)
[425.914901] Starting subtest: x-tiled-32bpp-rotate-270
[425.935238] Subtest x-tiled-32bpp-rotate-270: SKIP (0.020s)
[426.051848] [083/133] (604s left) gem_exec_fence (syncobj-invalid-flags)
[426.560553] Starting subtest: syncobj-invalid-flags
[426.562830] Subtest syncobj-invalid-flags: SUCCESS (0.001s)
[427.989184] [084/133] (602s left) kms_cursor_legacy (modeset-atomic-cursor-hotspot)
[428.110604] Starting subtest: modeset-atomic-cursor-hotspot
[428.114986] Subtest modeset-atomic-cursor-hotspot: SKIP (0.000s)
[428.259498] [085/133] (601s left) kms_getfb (getfb2-handle-closed)
[428.355046] Starting subtest: getfb2-handle-closed
[428.357143] Subtest getfb2-handle-closed: SUCCESS (0.000s)
[428.463803] [086/133] (601s left) gem_userptr_blits (readonly-unsync)
[428.521121] Subtest readonly-unsync: SKIP (0.000s)
[428.643648] [087/133] (601s left) gem_exec_capture (userptr)
[428.710675] Starting subtest: userptr
[429.556218] Subtest userptr: SUCCESS (0.845s)
[429.673210] [088/133] (600s left) gem_lmem_swapping (verify-ccs)
[429.715251] Subtest verify-ccs: SKIP (0.000s)
[429.757381] [089/133] (600s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-blt)
[429.859494] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-blt
[429.863451] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[429.961577] [090/133] (600s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[430.057916] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[430.062009] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[430.158236] [091/133] (600s left) syncobj_timeline (signal-array)
[430.222701] Starting subtest: signal-array
[430.225058] Subtest signal-array: SUCCESS (0.000s)
[430.322647] [092/133] (600s left) kms_frontbuffer_tracking (psr-2p-rte)
[430.437813] Starting subtest: psr-2p-rte
[430.441898] Subtest psr-2p-rte: SKIP (0.000s)
[430.537989] [093/133] (599s left) gem_exec_reloc (basic-cpu-gtt-noreloc)
[430.601789] Subtest basic-cpu-gtt-noreloc: SKIP (0.000s)
[430.684635] [094/133] (599s left) gem_exec_schedule (pi-shared-iova)
[430.859708] Starting subtest: pi-shared-iova
[430.862153] Starting dynamic subtest: rcs0
[430.937347] Dynamic subtest rcs0: SUCCESS (0.081s)
[430.953219] Starting dynamic subtest: vcs0
[431.003785] Dynamic subtest vcs0: SUCCESS (0.066s)
[431.018998] Starting dynamic subtest: vcs1
[431.059609] Dynamic subtest vcs1: SUCCESS (0.055s)
[431.077853] Starting dynamic subtest: vecs0
[431.116578] Dynamic subtest vecs0: SUCCESS (0.056s)
[431.131623] Starting dynamic subtest: ccs0
[431.178123] Dynamic subtest ccs0: SUCCESS (0.059s)
[431.180897] Subtest pi-shared-iova: SUCCESS (0.322s)
[432.956509] [095/133] (597s left) gem_softpin (evict-snoop)
[433.023363] Starting subtest: evict-snoop
[433.027726] Subtest evict-snoop: SKIP (0.000s)
[433.157500] [096/133] (597s left) gem_exec_reloc (basic-cpu-wc)
[433.222065] Subtest basic-cpu-wc: SKIP (0.000s)
[433.295016] [097/133] (597s left) gem_ctx_sseu (engines)
[433.363193] Subtest engines: SKIP (0.000s)
[433.449122] [098/133] (597s left) prime_mmap (test_correct_cpu_write)
[433.502576] Starting subtest: test_correct_cpu_write
[433.504878] Starting dynamic subtest: test_correct_cpu_write-smem
[433.507076] Dynamic subtest test_correct_cpu_write-smem: SUCCESS (0.000s)
[433.508986] Subtest test_correct_cpu_write: SUCCESS (0.001s)
[433.602746] [099/133] (597s left) kms_pm_dc (dc3co-vpb-simulation)
[434.706247] Starting subtest: dc3co-vpb-simulation
[444.022461] Subtest dc3co-vpb-simulation: SKIP (9.308s)
[444.141611] [100/133] (586s left) gem_ctx_persistence (process)
[444.195989] Starting subtest: process
[444.386854] Subtest process: SUCCESS (0.191s)
[444.476728] [101/133] (586s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[444.570258] Starting subtest: plane-upscale-20x20-with-rotation
[444.572549] Starting dynamic subtest: pipe-A
[444.795129] Dynamic subtest pipe-A: SUCCESS (0.227s)
[444.807775] Starting dynamic subtest: pipe-B
[446.056312] Dynamic subtest pipe-B: SUCCESS (1.260s)
[446.068679] Starting dynamic subtest: pipe-C
[447.393705] Dynamic subtest pipe-C: SUCCESS (1.336s)
[447.406034] Starting dynamic subtest: pipe-D
[448.726326] Dynamic subtest pipe-D: SUCCESS (1.332s)
[448.728715] Subtest plane-upscale-20x20-with-rotation: SUCCESS (4.159s)
[449.919072] [102/133] (581s left) kms_addfb_basic (bad-pitch-1024)
[449.980565] Starting subtest: bad-pitch-1024
[449.982849] Subtest bad-pitch-1024: SUCCESS (0.000s)
[450.117183] [103/133] (580s left) gem_exec_capture (capture)
[450.178565] Starting subtest: capture
[450.180876] Starting dynamic subtest: rcs0-smem
[451.007102] Dynamic subtest rcs0-smem: SUCCESS (0.828s)
[451.020989] Starting dynamic subtest: bcs0-smem
[451.832206] Dynamic subtest bcs0-smem: SUCCESS (0.825s)
[451.846185] Starting dynamic subtest: vcs0-smem
[452.661365] Dynamic subtest vcs0-smem: SUCCESS (0.827s)
[452.674139] Starting dynamic subtest: vcs1-smem
[453.479486] Dynamic subtest vcs1-smem: SUCCESS (0.817s)
[453.492338] Starting dynamic subtest: vecs0-smem
[454.878646] Dynamic subtest vecs0-smem: FAIL (1.341s)
[454.892022] Starting dynamic subtest: ccs0-smem
[456.139692] Dynamic subtest ccs0-smem: SUCCESS (1.318s)
[456.141954] Subtest capture: FAIL (5.962s)
[456.252472] [104/133] (574s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc)
[456.355756] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc
[456.359856] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[456.466581] [105/133] (574s left) kms_scaling_modes (scaling-mode-full)
[456.564034] Starting subtest: scaling-mode-full
[456.565950] Starting dynamic subtest: pipe-A-eDP-1
[456.658097] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.096s)
[456.674737] Starting dynamic subtest: pipe-B-eDP-1
[457.872009] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.212s)
[457.886235] Starting dynamic subtest: pipe-C-eDP-1
[459.069959] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.197s)
[459.082031] Starting dynamic subtest: pipe-D-eDP-1
[460.223562] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.152s)
[460.225716] Subtest scaling-mode-full: SUCCESS (3.664s)
[461.394952] [106/133] (569s left) kms_pm_rpm (pm-tiling)
[462.941916] Starting subtest: pm-tiling
[462.946925] Subtest pm-tiling: SKIP (0.000s)
[463.643531] [107/133] (567s left) kms_dsc (dsc-fractional-bpp)
[463.732314] Starting subtest: dsc-fractional-bpp
[463.745001] Subtest dsc-fractional-bpp: SKIP (0.000s)
[463.806451] [108/133] (567s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[463.902174] Starting subtest: yf-tiled-16bpp-rotate-180
[463.906403] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[464.019882] [109/133] (567s left) kms_frontbuffer_tracking (psr-rgb565-draw-pwrite)
[464.114552] Starting subtest: psr-rgb565-draw-pwrite
[471.157798] Subtest psr-rgb565-draw-pwrite: SUCCESS (7.047s)
[471.289549] [110/133] (560s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[471.392630] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip
[472.371304] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip: SUCCESS (0.979s)
[472.508583] [111/133] (558s left) perf (i915-ref-count)
[472.573961] Starting subtest: i915-ref-count
[472.613444] Subtest i915-ref-count: SUCCESS (0.036s)
[472.696192] [112/133] (558s left) kms_ccs (crc-primary-basic-4-tiled-bmg-ccs)
[472.780830] Starting subtest: crc-primary-basic-4-tiled-bmg-ccs
[472.784599] Subtest crc-primary-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[473.885493] [113/133] (557s left) gem_partial_pwrite_pread (writes-after-reads-snoop)
[473.938189] Subtest writes-after-reads-snoop: SKIP (0.000s)
[473.999735] [114/133] (557s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-plflip-blt)
[474.094592] Starting subtest: fbc-1p-primscrn-shrfb-plflip-blt
[478.717972] Subtest fbc-1p-primscrn-shrfb-plflip-blt: SUCCESS (4.626s)
[479.911079] [115/133] (551s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-modifiers)
[480.001895] Starting subtest: plane-scaler-with-clipping-clamping-modifiers
[480.004228] Starting dynamic subtest: pipe-A
[480.350622] Dynamic subtest pipe-A: SUCCESS (0.351s)
[480.363118] Starting dynamic subtest: pipe-B
[481.772522] Dynamic subtest pipe-B: SUCCESS (1.421s)
[481.784916] Starting dynamic subtest: pipe-C
[483.215253] Dynamic subtest pipe-C: SUCCESS (1.442s)
[483.230557] Starting dynamic subtest: pipe-D
[484.660610] Dynamic subtest pipe-D: SUCCESS (1.443s)
[484.662859] Subtest plane-scaler-with-clipping-clamping-modifiers: SUCCESS (4.662s)
[485.822377] [116/133] (545s left) syncobj_wait (wait-zero-handles)
[485.877412] Starting subtest: wait-zero-handles
[485.879755] Subtest wait-zero-handles: SUCCESS (0.000s)
[485.999127] [117/133] (545s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[486.092245] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu
[486.096531] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[486.183455] [118/133] (545s left) syncobj_timeline (etime-multi-wait-for-submit-submitted)
[486.250324] Starting subtest: etime-multi-wait-for-submit-submitted
[486.357579] Subtest etime-multi-wait-for-submit-submitted: SUCCESS (0.107s)
[486.435867] [119/133] (545s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-upscaling)
[486.530308] Starting subtest: flip-64bpp-4tile-to-32bpp-4tile-upscaling
[486.532431] Starting dynamic subtest: pipe-A-default-mode
[489.260341] Dynamic subtest pipe-A-default-mode: SUCCESS (2.731s)
[489.262957] Subtest flip-64bpp-4tile-to-32bpp-4tile-upscaling: SUCCESS (2.732s)
[490.100610] [120/133] (541s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[490.203843] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-gtt
[490.208266] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[490.316165] [121/133] (541s left) kms_lease (setcrtc-implicit-plane)
[490.418345] Starting subtest: setcrtc-implicit-plane
[490.420166] Starting dynamic subtest: pipe-A-eDP-1
[490.906320] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.488s)
[490.918575] Starting dynamic subtest: pipe-B-eDP-1
[492.048171] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.140s)
[492.060843] Starting dynamic subtest: pipe-C-eDP-1
[493.160444] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.113s)
[493.172333] Starting dynamic subtest: pipe-D-eDP-1
[494.288193] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.126s)
[494.290530] Subtest setcrtc-implicit-plane: SUCCESS (3.873s)
[495.146721] [122/133] (536s left) kms_chamelium_frames (hdmi-crc-fast)
[495.539798] Subtest hdmi-crc-fast: SKIP (0.000s)
[496.353126] [123/133] (535s left) gem_basic (multigpu-create-close)
[496.406335] Starting subtest: multigpu-create-close
[496.410344] Subtest multigpu-create-close: SKIP (0.000s)
[496.507198] [124/133] (535s left) kms_big_fb (y-tiled-addfb-size-overflow)
[496.604963] Starting subtest: y-tiled-addfb-size-overflow
[496.609414] Subtest y-tiled-addfb-size-overflow: SKIP (0.000s)
[496.718654] [125/133] (535s left) kms_chamelium_hpd (vga-hpd-enable-disable-mode)
[497.108849] Subtest vga-hpd-enable-disable-mode: SKIP (0.000s)
[497.891863] [126/133] (534s left) kms_color (ctm-0-25)
[497.976679] Starting subtest: ctm-0-25
[497.978603] Starting dynamic subtest: pipe-A-eDP-1
[501.426855] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.449s)
[501.439317] Starting dynamic subtest: pipe-B-eDP-1
[505.532721] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.105s)
[505.544935] Starting dynamic subtest: pipe-C-eDP-1
[509.489728] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.959s)
[509.502340] Starting dynamic subtest: pipe-D-eDP-1
[513.491343] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.998s)
[513.493542] Subtest ctm-0-25: SUCCESS (15.514s)
[514.331583] [127/133] (517s left) syncobj_basic (bad-create-flags)
[514.393264] Starting subtest: bad-create-flags
[514.395210] Subtest bad-create-flags: SUCCESS (0.000s)
[514.480705] [128/133] (517s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-blt)
[514.572297] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-blt
[518.729369] Subtest fbc-1p-offscreen-pri-indfb-draw-blt: SUCCESS (4.161s)
[519.936874] [129/133] (512s left) gem_exec_flush (basic-wb-set-default)
[520.007650] Starting subtest: basic-wb-set-default
[525.493502] Subtest basic-wb-set-default: SUCCESS (5.460s)
[525.673503] [130/133] (506s left) syncobj_timeline (multi-wait-available-submitted)
[525.727418] Starting subtest: multi-wait-available-submitted
[525.729785] Subtest multi-wait-available-submitted: SUCCESS (0.000s)
[525.839252] [131/133] (506s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-wc)
[525.934982] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-wc
[529.536895] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (3.605s)
[530.723498] [132/133] (501s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf)
[530.831695] Starting subtest: fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf
[530.834312] Starting dynamic subtest: pipe-A-eDP-1
[530.903052] Dynamic subtest pipe-A-eDP-1: SKIP (0.078s)
[530.921974] Starting dynamic subtest: pipe-B-eDP-1
[531.987929] Dynamic subtest pipe-B-eDP-1: SKIP (1.081s)
[531.992308] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf: SKIP (1.163s)
[533.192717] [133/133] (499s left) kms_frontbuffer_tracking (fbcpsr-2p-indfb-fliptrack-mmap-gtt)
[533.289863] Starting subtest: fbcpsr-2p-indfb-fliptrack-mmap-gtt
[533.294041] Subtest fbcpsr-2p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[533.394332] Closing watchdogs
results: parsing output: 0/ for test: i915_pm_freq_api
results: parsing output: 1/ for test: syncobj_wait
results: parsing output: 2/ for test: sw_sync
results: parsing output: 3/ for test: kms_ccs
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: prime_vgem
results: parsing output: 6/ for test: gem_pxp
results: parsing output: 7/ for test: kms_psr
results: parsing output: 8/ for test: perf
results: parsing output: 9/ for test: i915_pm_rps
results: parsing output: 10/ for test: gen9_exec_parse
results: parsing output: 11/ for test: gem_exercise_blt
results: parsing output: 12/ for test: gem_userptr_blits
results: parsing output: 13/ for test: gen9_exec_parse
results: parsing output: 14/ for test: gem_exec_whisper
results: parsing output: 15/ for test: gem_fence_thrash
results: parsing output: 16/ for test: gem_tiled_pread_basic
results: parsing output: 17/ for test: kms_vblank
results: parsing output: 18/ for test: api_intel_bb
results: parsing output: 19/ for test: kms_addfb_basic
results: parsing output: 20/ for test: kms_content_protection
results: parsing output: 21/ for test: kms_cursor_legacy
results: parsing output: 22/ for test: gem_exec_params
results: parsing output: 23/ for test: gem_exec_suspend
results: parsing output: 24/ for test: drm_mm
results: parsing output: 25/ for test: kms_flip_scaled_crc
results: parsing output: 26/ for test: gem_exec_balancer
results: parsing output: 27/ for test: kms_ccs
results: parsing output: 28/ for test: gem_ctx_isolation
results: parsing output: 29/ for test: gem_mmap
results: parsing output: 30/ for test: kms_big_fb
results: parsing output: 31/ for test: syncobj_eventfd
results: parsing output: 32/ for test: kms_rmfb
results: parsing output: 33/ for test: kms_cursor_crc
results: parsing output: 34/ for test: kms_frontbuffer_tracking
results: parsing output: 35/ for test: kms_universal_plane
results: parsing output: 36/ for test: gem_spin_batch
results: parsing output: 37/ for test: kms_flip
results: parsing output: 38/ for test: prime_busy
results: parsing output: 39/ for test: kms_addfb_basic
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: gem_sync
results: parsing output: 42/ for test: kms_ccs
results: parsing output: 43/ for test: kms_pipe_crc_basic
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: gem_ppgtt
results: parsing output: 46/ for test: kms_psr
results: parsing output: 47/ for test: kms_big_fb
results: parsing output: 48/ for test: gem_mmap_gtt
results: parsing output: 49/ for test: gem_exec_schedule
results: parsing output: 50/ for test: gem_create
results: parsing output: 51/ for test: kms_psr2_sf
results: parsing output: 52/ for test: gem_fence_thrash
results: parsing output: 53/ for test: perf
results: parsing output: 54/ for test: gem_userptr_blits
results: parsing output: 55/ for test: kms_plane
results: parsing output: 56/ for test: gem_render_copy
results: parsing output: 57/ for test: kms_ccs
results: parsing output: 58/ for test: kms_plane
results: parsing output: 59/ for test: kms_psr
results: parsing output: 60/ for test: perf_pmu
results: parsing output: 61/ for test: perf
results: parsing output: 62/ for test: kms_chamelium_audio
results: parsing output: 63/ for test: i915_pm_rps
results: parsing output: 64/ for test: syncobj_timeline
results: parsing output: 65/ for test: kms_cursor_crc
results: parsing output: 66/ for test: gem_mmap
results: parsing output: 67/ for test: kms_big_fb
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: kms_joiner
results: parsing output: 70/ for test: gem_workarounds
results: parsing output: 71/ for test: gem_mmap_gtt
results: parsing output: 72/ for test: kms_psr
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: kms_addfb_basic
results: parsing output: 75/ for test: kms_cursor_crc
results: parsing output: 76/ for test: gem_pread
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: kms_psr2_sf
results: parsing output: 79/ for test: gem_readwrite
results: parsing output: 80/ for test: api_intel_bb
results: parsing output: 81/ for test: kms_big_fb
results: parsing output: 82/ for test: gem_exec_fence
results: parsing output: 83/ for test: kms_cursor_legacy
results: parsing output: 84/ for test: kms_getfb
results: parsing output: 85/ for test: gem_userptr_blits
results: parsing output: 86/ for test: gem_exec_capture
results: parsing output: 87/ for test: gem_lmem_swapping
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: syncobj_timeline
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: gem_exec_reloc
results: parsing output: 93/ for test: gem_exec_schedule
results: parsing output: 94/ for test: gem_softpin
results: parsing output: 95/ for test: gem_exec_reloc
results: parsing output: 96/ for test: gem_ctx_sseu
results: parsing output: 97/ for test: prime_mmap
results: parsing output: 98/ for test: kms_pm_dc
results: parsing output: 99/ for test: gem_ctx_persistence
results: parsing output: 100/ for test: kms_plane_scaling
results: parsing output: 101/ for test: kms_addfb_basic
results: parsing output: 102/ for test: gem_exec_capture
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: kms_scaling_modes
results: parsing output: 105/ for test: kms_pm_rpm
results: parsing output: 106/ for test: kms_dsc
results: parsing output: 107/ for test: kms_big_fb
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: kms_big_fb
results: parsing output: 110/ for test: perf
results: parsing output: 111/ for test: kms_ccs
results: parsing output: 112/ for test: gem_partial_pwrite_pread
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: kms_plane_scaling
results: parsing output: 115/ for test: syncobj_wait
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: syncobj_timeline
results: parsing output: 118/ for test: kms_flip_scaled_crc
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: kms_lease
results: parsing output: 121/ for test: kms_chamelium_frames
results: parsing output: 122/ for test: gem_basic
results: parsing output: 123/ for test: kms_big_fb
results: parsing output: 124/ for test: kms_chamelium_hpd
results: parsing output: 125/ for test: kms_color
results: parsing output: 126/ for test: syncobj_basic
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: gem_exec_flush
results: parsing output: 129/ for test: syncobj_timeline
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: kms_psr2_sf
results: parsing output: 132/ for test: kms_frontbuffer_tracking
Done.