[57.191507] Initializing watchdogs
[57.191552]   /dev/watchdog0
[57.205770] [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.209991] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.212123] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.215610] [001/132] (960s left) kms_big_fb (4-tiled-addfb-size-offset-overflow)
[57.337463] Starting subtest: 4-tiled-addfb-size-offset-overflow
[57.339420] Subtest 4-tiled-addfb-size-offset-overflow: SUCCESS (0.000s)
[57.449921] [002/132] (960s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf)
[57.548707] Starting subtest: fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf
[57.551079] Starting dynamic subtest: pipe-A-eDP-1
[57.668385] Dynamic subtest pipe-A-eDP-1: SKIP (0.124s)
[57.676069] Starting dynamic subtest: pipe-B-eDP-1
[58.824971] Dynamic subtest pipe-B-eDP-1: SKIP (1.158s)
[58.829491] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf: SKIP (1.284s)
[60.101341] [003/132] (957s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-msflip-blt)
[60.209643] Starting subtest: fbcpsr-2p-primscrn-indfb-msflip-blt
[60.214298] Subtest fbcpsr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[60.340709] [004/132] (957s left) kms_cursor_crc (cursor-rapid-movement-256x256)
[60.590491] Starting subtest: cursor-rapid-movement-256x256
[60.592766] Starting dynamic subtest: pipe-A-eDP-1
[61.152346] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.561s)
[61.182223] Starting dynamic subtest: pipe-D-eDP-1
[62.395365] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.220s)
[62.397412] Subtest cursor-rapid-movement-256x256: SUCCESS (1.809s)
[63.257024] [005/132] (954s left) syncobj_wait (wait-delayed-signal)
[63.314920] Starting subtest: wait-delayed-signal
[63.418127] Subtest wait-delayed-signal: SUCCESS (0.103s)
[63.512194] [006/132] (954s left) gem_ctx_param (root-set)
[63.568665] Starting subtest: root-set
[63.571070] Subtest root-set: SUCCESS (0.001s)
[63.669464] [007/132] (954s left) gem_render_copy (y-tiled-ccs-to-y-tiled-mc-ccs)
[63.722814] Starting subtest: y-tiled-ccs-to-y-tiled-mc-ccs
[63.727232] Subtest y-tiled-ccs-to-y-tiled-mc-ccs: SKIP (0.000s)
[63.907920] [008/132] (954s left) i915_query (query-regions-garbage-items)
[63.964535] Starting subtest: query-regions-garbage-items
[63.966619] Subtest query-regions-garbage-items: SUCCESS (0.000s)
[64.057237] [009/132] (953s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-upscaling)
[64.150253] Starting subtest: flip-32bpp-linear-to-64bpp-linear-upscaling
[64.152613] Starting dynamic subtest: pipe-A-default-mode
[66.852074] Dynamic subtest pipe-A-default-mode: SUCCESS (2.704s)
[66.854067] Subtest flip-32bpp-linear-to-64bpp-linear-upscaling: SUCCESS (2.705s)
[67.740959] [010/132] (950s left) kms_plane_scaling (planes-downscale-factor-0-75-unity-scaling)
[67.902655] Starting subtest: planes-downscale-factor-0-75-unity-scaling
[67.904830] Starting dynamic subtest: pipe-A
[67.967418] Dynamic subtest pipe-A: SUCCESS (0.067s)
[67.977038] Starting dynamic subtest: pipe-B
[69.138112] Dynamic subtest pipe-B: SUCCESS (1.171s)
[69.151317] Starting dynamic subtest: pipe-C
[70.254952] Dynamic subtest pipe-C: SUCCESS (1.117s)
[70.263655] Starting dynamic subtest: pipe-D
[71.379236] Dynamic subtest pipe-D: SUCCESS (1.124s)
[71.381465] Subtest planes-downscale-factor-0-75-unity-scaling: SUCCESS (3.482s)
[72.577465] [011/132] (945s left) kms_psr (fbc-psr2-primary-blt)
[72.669956] Starting subtest: fbc-psr2-primary-blt
[72.672164] Starting dynamic subtest: eDP-1
[72.676147] Dynamic subtest eDP-1: SKIP (0.000s)
[72.680174] Subtest fbc-psr2-primary-blt: SKIP (0.001s)
[72.768359] [012/132] (945s left) kms_pm_lpsp (kms-lpsp)
[73.883328] Starting subtest: kms-lpsp
[73.885774] Starting dynamic subtest: pipe-A-eDP-1
[74.029095] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.149s)
[74.314365] Subtest kms-lpsp: SUCCESS (0.433s)
[75.171106] [013/132] (943s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted)
[75.232941] Starting subtest: multi-wait-available-unsubmitted-submitted
[75.235330] Subtest multi-wait-available-unsubmitted-submitted: SUCCESS (0.000s)
[75.364428] [014/132] (942s left) kms_atomic_interruptible (universal-setplane-primary)
[75.454285] Starting subtest: universal-setplane-primary
[75.456576] Starting dynamic subtest: pipe-A-eDP-1
[82.890068] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.436s)
[82.892445] Subtest universal-setplane-primary: SUCCESS (7.437s)
[83.748586] [015/132] (934s left) api_intel_allocator (two-level-inception-interruptible)
[83.811844] Starting subtest: two-level-inception-interruptible
[89.266758] Subtest two-level-inception-interruptible: SUCCESS (5.406s)
[89.352591] [016/132] (928s left) kms_joiner (basic-force-ultra-joiner)
[89.482055] Starting subtest: basic-force-ultra-joiner
[89.486434] Subtest basic-force-ultra-joiner: SKIP (0.000s)
[89.564509] [017/132] (928s left) kms_getfb (getfb-handle-zero)
[89.659873] Starting subtest: getfb-handle-zero
[89.662227] Subtest getfb-handle-zero: SUCCESS (0.000s)
[89.799503] [018/132] (928s left) kms_fbcon_fbt (psr)
[89.867999] Starting subtest: psr
[97.135849] Subtest psr: SUCCESS (7.265s)
[97.210058] [019/132] (921s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[97.299387] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[97.303755] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[97.417829] [020/132] (921s left) kms_chamelium_hpd (vga-hpd-without-ddc)
[97.808884] Subtest vga-hpd-without-ddc: SKIP (0.000s)
[98.661334] [021/132] (919s left) gem_exec_whisper (basic-sync-all)
[98.725906] Starting subtest: basic-sync-all
[105.516609] Subtest basic-sync-all: SUCCESS (6.789s)
[105.707513] [022/132] (912s left) kms_big_fb (x-tiled-32bpp-rotate-90)
[105.801563] Starting subtest: x-tiled-32bpp-rotate-90
[105.819735] Subtest x-tiled-32bpp-rotate-90: SKIP (0.018s)
[105.929884] [023/132] (912s left) kms_psr (fbc-pr-basic)
[106.017045] Starting subtest: fbc-pr-basic
[106.021279] Subtest fbc-pr-basic: SKIP (0.000s)
[106.093599] [024/132] (912s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[106.183102] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[106.187491] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[106.302640] [025/132] (912s left) perf_pmu (init-wait)
[106.349655] Starting subtest: init-wait
[106.352106] Starting dynamic subtest: rcs0
[106.354448] Dynamic subtest rcs0: SUCCESS (0.002s)
[106.365269] Starting dynamic subtest: bcs0
[106.367462] Dynamic subtest bcs0: SUCCESS (0.000s)
[106.378239] Starting dynamic subtest: vcs0
[106.380329] Dynamic subtest vcs0: SUCCESS (0.001s)
[106.389065] Starting dynamic subtest: vcs1
[106.390992] Dynamic subtest vcs1: SUCCESS (0.001s)
[106.397359] Starting dynamic subtest: vecs0
[106.399250] Dynamic subtest vecs0: SUCCESS (0.000s)
[106.405618] Starting dynamic subtest: ccs0
[106.407505] Dynamic subtest ccs0: SUCCESS (0.000s)
[106.409386] Subtest init-wait: SUCCESS (0.006s)
[106.498232] [026/132] (912s left) kms_legacy_colorkey (invalid-plane)
[106.599455] Starting subtest: invalid-plane
[106.601339] Starting dynamic subtest: zero-id
[106.603220] Dynamic subtest zero-id: SUCCESS (0.000s)
[106.610049] Starting dynamic subtest: outrange-id
[106.612297] Dynamic subtest outrange-id: SUCCESS (0.000s)
[106.614422] Subtest invalid-plane: SUCCESS (0.000s)
[106.678471] [027/132] (912s left) kms_vblank (wait-busy-hang)
[106.770157] Starting subtest: wait-busy-hang
[106.772202] Starting dynamic subtest: pipe-A-eDP-1
[133.651807] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.882s)
[133.665144] Starting dynamic subtest: pipe-D-eDP-1
[158.671275] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.014s)
[158.673636] Subtest wait-busy-hang: SUCCESS (51.899s)
[159.540108] [028/132] (859s left) syncobj_eventfd (binary-wait)
[159.606041] Starting subtest: binary-wait
[159.614794] Subtest binary-wait: SUCCESS (0.010s)
[159.718956] [029/132] (859s left) gem_mmap_gtt (basic-read-write)
[159.783309] Subtest basic-read-write: SKIP (0.000s)
[159.846239] [030/132] (858s left) kms_cursor_legacy (basic-busy-flip-before-cursor-varying-size)
[159.985609] Starting subtest: basic-busy-flip-before-cursor-varying-size
[159.990228] Subtest basic-busy-flip-before-cursor-varying-size: SKIP (0.000s)
[160.137985] [031/132] (858s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-blt)
[160.251472] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-blt
[164.694856] Subtest fbc-1p-primscrn-pri-indfb-draw-blt: SUCCESS (4.447s)
[165.854303] [032/132] (853s left) gem_exec_fence (syncobj-repeat)
[166.274931] Starting subtest: syncobj-repeat
[166.447698] Subtest syncobj-repeat: SUCCESS (0.171s)
[167.962969] [FACT gem_exec_fence (syncobj-repeat)] new: kernel.kmod_is_loaded.vgem: true
[167.966153] [033/132] (850s left) gem_mmap_gtt (basic-wc)
[168.032760] Subtest basic-wc: SKIP (0.000s)
[168.098905] [034/132] (850s left) kms_atomic (test-only)
[168.197110] Starting subtest: test-only
[168.247669] Starting dynamic subtest: pipe-A-eDP-1
[170.768086] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.520s)
[170.770305] Subtest test-only: SUCCESS (2.571s)
[171.665496] [035/132] (847s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite)
[171.771256] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite
[171.775584] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[171.891217] [036/132] (847s left) kms_cursor_legacy (nonblocking-modeset-vs-cursor-atomic)
[172.022121] Starting subtest: nonblocking-modeset-vs-cursor-atomic
[173.412454] Subtest nonblocking-modeset-vs-cursor-atomic: SUCCESS (1.391s)
[174.299919] [037/132] (844s left) kms_vrr (seamless-rr-switch-drrs)
[174.406408] Starting subtest: seamless-rr-switch-drrs
[174.413172] Subtest seamless-rr-switch-drrs: SKIP (0.000s)
[174.520636] [038/132] (844s left) kms_content_protection (content-type-change)
[174.684832] Starting subtest: content-type-change
[174.689521] Subtest content-type-change: SKIP (0.000s)
[174.836145] [039/132] (844s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-downscaling)
[174.938203] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-downscaling
[174.942630] Subtest flip-64bpp-yftile-to-16bpp-yftile-downscaling: SKIP (0.000s)
[175.019174] [040/132] (844s left) i915_pm_rpm (system-suspend-devices)
[177.060637] Starting subtest: system-suspend-devices
[184.841025] Subtest system-suspend-devices: SUCCESS (7.782s)
[185.235864] [041/132] (834s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180)
[185.357456] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180
[186.312232] Subtest x-tiled-max-hw-stride-64bpp-rotate-180: SUCCESS (0.956s)
[186.467918] [042/132] (832s left) gem_mmap_gtt (basic-write-read-distinct)
[186.529070] Subtest basic-write-read-distinct: SKIP (0.000s)
[186.597779] [043/132] (832s left) gem_ctx_shared (q-in-order)
[186.656325] Starting subtest: Q-in-order
[186.658256] Starting dynamic subtest: rcs0
[186.753446] Dynamic subtest rcs0: SUCCESS (0.096s)
[186.769149] Starting dynamic subtest: vcs0
[186.823799] Dynamic subtest vcs0: SUCCESS (0.070s)
[186.838699] Starting dynamic subtest: vcs1
[186.893324] Dynamic subtest vcs1: SUCCESS (0.069s)
[186.908354] Starting dynamic subtest: vecs0
[186.963315] Dynamic subtest vecs0: SUCCESS (0.069s)
[186.978061] Starting dynamic subtest: ccs0
[187.029898] Dynamic subtest ccs0: SUCCESS (0.065s)
[187.032184] Subtest Q-in-order: SUCCESS (0.373s)
[188.048221] [044/132] (831s left) syncobj_timeline (wait-all-complex)
[188.110333] Starting subtest: wait-all-complex
[188.322833] Subtest wait-all-complex: SUCCESS (0.212s)
[188.411764] [045/132] (831s left) kms_async_flips (alternate-sync-async-flip-atomic)
[188.505463] Starting subtest: alternate-sync-async-flip-atomic
[188.877021] Starting dynamic subtest: pipe-A-eDP-1
[191.706072] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.829s)
[192.085063] Starting dynamic subtest: pipe-B-eDP-1
[194.835044] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.758s)
[195.217741] Starting dynamic subtest: pipe-C-eDP-1
[197.966272] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.757s)
[198.334074] Starting dynamic subtest: pipe-D-eDP-1
[201.093458] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.766s)
[201.095625] Subtest alternate-sync-async-flip-atomic: SUCCESS (12.591s)
[202.274052] [046/132] (817s left) gem_ctx_create (basic-files)
[202.353252] Starting subtest: basic-files
[204.363148] Subtest basic-files: SUCCESS (2.010s)
[205.962820] [047/132] (813s left) gem_tiling_max_stride
[206.086827] [048/132] (813s left) i915_drm_fdinfo (virtual-idle)
[206.133577] Starting subtest: virtual-idle
[209.225210] Subtest virtual-idle: SUCCESS (3.089s)
[209.309342] [049/132] (810s left) kms_chamelium_edid (dp-edid-read)
[209.705736] Subtest dp-edid-read: SKIP (0.000s)
[210.473822] [050/132] (809s left) gem_ctx_persistence (process)
[210.536919] Starting subtest: process
[210.765279] Subtest process: SUCCESS (0.228s)
[210.866884] [051/132] (808s left) kms_ccs (crc-primary-suspend-y-tiled-gen12-mc-ccs)
[210.972919] Starting subtest: crc-primary-suspend-y-tiled-gen12-mc-ccs
[210.975261] Starting dynamic subtest: pipe-A-eDP-1
[210.984215] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[210.994385] Starting dynamic subtest: pipe-B-eDP-1
[211.003294] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[211.011859] Starting dynamic subtest: pipe-C-eDP-1
[211.020210] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[211.028737] Starting dynamic subtest: pipe-D-eDP-1
[211.037501] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[211.041843] Subtest crc-primary-suspend-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[213.178421] [052/132] (806s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[213.277084] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[213.279255] Starting dynamic subtest: A-eDP1
[216.900906] Dynamic subtest A-eDP1: SUCCESS (3.620s)
[216.915313] Starting dynamic subtest: B-eDP1
[220.103949] Dynamic subtest B-eDP1: SUCCESS (3.202s)
[220.113803] Starting dynamic subtest: C-eDP1
[223.224129] Dynamic subtest C-eDP1: SUCCESS (3.121s)
[223.232704] Starting dynamic subtest: D-eDP1
[226.326887] Dynamic subtest D-eDP1: SUCCESS (3.099s)
[226.329148] Subtest single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SUCCESS (13.050s)
[227.132206] [053/132] (792s left) core_setmaster (master-drop-set-shared-fd)
[227.219558] Starting subtest: master-drop-set-shared-fd
[227.238300] Subtest master-drop-set-shared-fd: SUCCESS (0.021s)
[227.279125] [054/132] (792s left) api_intel_bb (simple-bb)
[227.340964] Starting subtest: simple-bb
[227.347221] Subtest simple-bb: SUCCESS (0.008s)
[227.493537] [055/132] (792s left) gem_exec_suspend (basic-s3-devices)
[227.567681] Starting subtest: basic-S3-devices
[227.570080] Starting dynamic subtest: smem
[238.869490] Dynamic subtest smem: SUCCESS (11.300s)
[238.872080] Subtest basic-S3-devices: SUCCESS (11.302s)
[239.064857] [056/132] (780s left) gem_exec_reloc (basic-write-gtt-active)
[239.118879] Subtest basic-write-gtt-active: SKIP (0.000s)
[239.203174] [057/132] (780s left) syncobj_wait (single-wait-all-submitted)
[239.254059] Starting subtest: single-wait-all-submitted
[239.360550] Subtest single-wait-all-submitted: SUCCESS (0.107s)
[239.453049] [058/132] (780s left) gem_exec_schedule (submit-late-slice)
[239.527558] Starting subtest: submit-late-slice
[239.529517] Starting dynamic subtest: rcs0
[239.588058] Dynamic subtest rcs0: SUCCESS (0.068s)
[239.599278] Starting dynamic subtest: bcs0
[239.633110] Dynamic subtest bcs0: SUCCESS (0.045s)
[239.642745] Starting dynamic subtest: vcs0
[239.689665] Dynamic subtest vcs0: SUCCESS (0.055s)
[239.701264] Starting dynamic subtest: vcs1
[239.731414] Dynamic subtest vcs1: SUCCESS (0.042s)
[239.741657] Starting dynamic subtest: vecs0
[239.779301] Dynamic subtest vecs0: SUCCESS (0.047s)
[239.789997] Starting dynamic subtest: ccs0
[239.832102] Dynamic subtest ccs0: SUCCESS (0.052s)
[239.835435] Subtest submit-late-slice: SUCCESS (0.313s)
[240.978724] [059/132] (779s left) kms_flip (2x-wf_vblank-ts-check-interruptible)
[241.095365] Starting subtest: 2x-wf_vblank-ts-check-interruptible
[241.107269] Subtest 2x-wf_vblank-ts-check-interruptible: SKIP (0.010s)
[241.254807] [060/132] (778s left) gem_pxp (create-valid-protected-context)
[241.381047] Starting subtest: create-valid-protected-context
[241.383868] Subtest create-valid-protected-context: SUCCESS (0.002s)
[241.530954] [061/132] (778s left) kms_frontbuffer_tracking (fbcpsr-tiling-linear)
[241.652216] Starting subtest: fbcpsr-tiling-linear
[248.233427] Subtest fbcpsr-tiling-linear: SUCCESS (6.585s)
[248.397382] [062/132] (771s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf)
[248.503850] Starting subtest: fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf
[248.506128] Starting dynamic subtest: pipe-A-eDP-1
[248.576991] Dynamic subtest pipe-A-eDP-1: SKIP (0.077s)
[248.586127] Starting dynamic subtest: pipe-B-eDP-1
[249.704094] Dynamic subtest pipe-B-eDP-1: SKIP (1.125s)
[249.708524] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf: SKIP (1.205s)
[250.903647] [063/132] (769s left) gem_ctx_persistence (engines-mixed-process)
[250.977149] Starting subtest: engines-mixed-process
[250.979348] Starting dynamic subtest: rcs0
[252.904065] Dynamic subtest rcs0: SUCCESS (1.929s)
[252.915311] Starting dynamic subtest: bcs0
[254.884795] Dynamic subtest bcs0: SUCCESS (1.979s)
[254.896666] Starting dynamic subtest: vcs0
[256.939670] Dynamic subtest vcs0: SUCCESS (2.052s)
[256.953690] Starting dynamic subtest: vcs1
[258.932200] Dynamic subtest vcs1: SUCCESS (1.990s)
[258.946806] Starting dynamic subtest: vecs0
[260.922351] Dynamic subtest vecs0: SUCCESS (1.987s)
[260.934166] Starting dynamic subtest: ccs0
[262.898352] Dynamic subtest ccs0: SUCCESS (1.974s)
[262.901325] Subtest engines-mixed-process: SUCCESS (11.924s)
[262.991546] [064/132] (757s left) gem_ccs (block-copy-compressed)
[263.056991] Starting subtest: block-copy-compressed
[263.061325] Subtest block-copy-compressed: SKIP (0.000s)
[263.166418] [065/132] (757s left) gem_eio (unwedge-stress)
[263.408663] Starting subtest: unwedge-stress
[263.411056] Starting dynamic subtest: render
[268.529883] Dynamic subtest render: SUCCESS (5.121s)
[268.546548] Starting dynamic subtest: bsd1
[286.336825] Dynamic subtest bsd1: SUCCESS (17.806s)
[286.352835] Starting dynamic subtest: bsd2
[306.421341] Dynamic subtest bsd2: SUCCESS (20.083s)
[306.437808] Starting dynamic subtest: blt
[306.442332] Dynamic subtest blt: SKIP (0.000s)
[306.451522] Starting dynamic subtest: vebox
[325.983261] Dynamic subtest vebox: SUCCESS (19.559s)
[325.985334] Subtest unwedge-stress: SUCCESS (62.575s)
[327.949286] [066/132] (692s left) kms_pipe_b_c_ivb (pipe-b-double-modeset-then-modeset-pipe-c)
[328.015592] Subtest pipe-B-double-modeset-then-modeset-pipe-C: SKIP (0.000s)
[328.086201] [067/132] (692s left) kms_flip (busy-flip)
[328.183597] Starting subtest: busy-flip
[328.185553] Starting dynamic subtest: A-eDP1
[329.903964] Dynamic subtest A-eDP1: SUCCESS (1.722s)
[329.913558] Starting dynamic subtest: B-eDP1
[331.543325] Dynamic subtest B-eDP1: SUCCESS (1.638s)
[331.554739] Starting dynamic subtest: C-eDP1
[333.189709] Dynamic subtest C-eDP1: SUCCESS (1.645s)
[333.202960] Starting dynamic subtest: D-eDP1
[334.848033] Dynamic subtest D-eDP1: SUCCESS (1.657s)
[334.850346] Subtest busy-flip: SUCCESS (6.671s)
[336.014526] [068/132] (684s left) i915_drm_fdinfo (virtual-busy-hang)
[336.063824] Starting subtest: virtual-busy-hang
[336.068267] Subtest virtual-busy-hang: SKIP (0.000s)
[336.139361] [069/132] (684s left) kms_plane (pixel-format-4-tiled-lnl-ccs-modifier)
[336.240278] Starting subtest: pixel-format-4-tiled-lnl-ccs-modifier
[336.244600] Subtest pixel-format-4-tiled-lnl-ccs-modifier: SKIP (0.000s)
[336.340430] [070/132] (684s left) kms_psr_stress_test (invalidate-primary-flip-overlay)
[336.441698] Starting subtest: invalidate-primary-flip-overlay
[347.259575] Subtest invalidate-primary-flip-overlay: SUCCESS (10.819s)
[347.399542] [071/132] (673s left) kms_pm_rpm (system-suspend-idle)
[349.120995] Starting subtest: system-suspend-idle
[368.269278] Subtest system-suspend-idle: SUCCESS (4.341s)
[368.661851] [072/132] (651s left) gem_ccs (suspend-resume)
[368.741712] Starting subtest: suspend-resume
[368.746259] Subtest suspend-resume: SKIP (0.000s)
[368.864988] [073/132] (651s left) kms_big_fb (linear-16bpp-rotate-180)
[368.969504] Starting subtest: linear-16bpp-rotate-180
[370.365304] Subtest linear-16bpp-rotate-180: SUCCESS (1.413s)
[370.508130] [074/132] (650s left) kms_plane_lowres (tiling-x)
[370.598371] Starting subtest: tiling-x
[370.600250] Starting dynamic subtest: pipe-A-eDP-1
[370.603900] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[370.905942] Starting dynamic subtest: pipe-B-eDP-1
[370.910556] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[370.919822] Starting dynamic subtest: pipe-C-eDP-1
[370.924290] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[370.932975] Starting dynamic subtest: pipe-D-eDP-1
[370.937301] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[370.941507] Subtest tiling-x: SKIP (0.308s)
[371.760558] [075/132] (648s left) gem_close_race (basic-threads)
[371.830965] Starting subtest: basic-threads
[373.226690] Subtest basic-threads: SUCCESS (1.407s)
[373.420755] [076/132] (647s left) kms_getfb (getfb-handle-protection)
[373.517501] Starting subtest: getfb-handle-protection
[373.539885] Subtest getfb-handle-protection: SUCCESS (0.023s)
[373.687697] [077/132] (647s left) kms_feature_discovery (display)
[373.782943] Starting subtest: display
[373.784793] Subtest display: SUCCESS (0.000s)
[373.865966] [078/132] (646s left) kms_chamelium_color (ctm-0-25)
[373.956905] Subtest ctm-0-25: SKIP (0.000s)
[373.998855] [079/132] (646s left) kms_big_fb (y-tiled-8bpp-rotate-180)
[374.096621] Starting subtest: y-tiled-8bpp-rotate-180
[374.101107] Subtest y-tiled-8bpp-rotate-180: SKIP (0.000s)
[374.202548] [080/132] (646s left) kms_cursor_legacy (flip-vs-cursor-crc-atomic)
[374.316319] Starting subtest: flip-vs-cursor-crc-atomic
[374.993101] Subtest flip-vs-cursor-crc-atomic: SUCCESS (0.681s)
[375.858592] [081/132] (645s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-msflip-blt)
[375.961830] Starting subtest: fbcpsr-1p-primscrn-indfb-msflip-blt
[383.414176] Subtest fbcpsr-1p-primscrn-indfb-msflip-blt: SUCCESS (7.481s)
[383.582101] [082/132] (637s left) kms_frontbuffer_tracking (psr-1p-rte)
[383.693265] Starting subtest: psr-1p-rte
[389.085324] Subtest psr-1p-rte: SUCCESS (5.405s)
[389.258026] [083/132] (631s left) gem_wait (write-wait)
[389.340781] Starting subtest: write-wait
[389.343028] Starting dynamic subtest: all-engines
[390.480855] Dynamic subtest all-engines: SUCCESS (1.141s)
[390.496722] Starting dynamic subtest: rcs0
[391.544396] Dynamic subtest rcs0: SUCCESS (1.062s)
[391.561748] Starting dynamic subtest: bcs0
[392.603871] Dynamic subtest bcs0: SUCCESS (1.058s)
[392.620695] Starting dynamic subtest: vcs0
[393.660928] Dynamic subtest vcs0: SUCCESS (1.056s)
[393.677332] Starting dynamic subtest: vcs1
[394.713870] Dynamic subtest vcs1: SUCCESS (1.052s)
[394.731363] Starting dynamic subtest: vecs0
[395.768613] Dynamic subtest vecs0: SUCCESS (1.053s)
[395.785710] Starting dynamic subtest: ccs0
[396.823884] Dynamic subtest ccs0: SUCCESS (1.054s)
[396.826634] Subtest write-wait: SUCCESS (7.492s)
[397.033496] [084/132] (624s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[397.139002] Starting subtest: bad-pixel-format-y-tiled-ccs
[397.141364] Starting dynamic subtest: pipe-A-eDP-1
[397.164791] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[397.176719] Starting dynamic subtest: pipe-B-eDP-1
[397.198445] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[397.207453] Starting dynamic subtest: pipe-C-eDP-1
[397.229637] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[397.239553] Starting dynamic subtest: pipe-D-eDP-1
[397.261740] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[397.266054] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.004s)
[399.370564] [085/132] (621s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-wc)
[399.477149] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-wc
[399.481136] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[399.582758] [086/132] (621s left) kms_frontbuffer_tracking (fbc-shrfb-scaledprimary)
[399.690223] Starting subtest: fbc-shrfb-scaledprimary
[402.691205] Subtest fbc-shrfb-scaledprimary: SUCCESS (3.004s)
[403.870815] [087/132] (617s left) kms_ccs (missing-ccs-buffer-y-tiled-gen12-rc-ccs)
[403.961867] Starting subtest: missing-ccs-buffer-y-tiled-gen12-rc-ccs
[403.964318] Starting dynamic subtest: pipe-A-eDP-1
[403.986962] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[404.002020] Starting dynamic subtest: pipe-B-eDP-1
[404.023427] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[404.032040] Starting dynamic subtest: pipe-C-eDP-1
[404.052801] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[404.061515] Starting dynamic subtest: pipe-D-eDP-1
[404.083264] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[404.087582] Subtest missing-ccs-buffer-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[406.137675] [088/132] (615s left) gen9_exec_parse (bb-large)
[406.203497] Subtest bb-large: SKIP (0.000s)
[406.278176] [089/132] (614s left) perf (i915-ref-count)
[406.349053] Starting subtest: i915-ref-count
[406.465775] Subtest i915-ref-count: SUCCESS (0.114s)
[406.523586] [090/132] (614s left) sw_sync (alloc_fence_invalid_timeline)
[406.561959] Starting subtest: alloc_fence_invalid_timeline
[406.563870] Subtest alloc_fence_invalid_timeline: SUCCESS (0.000s)
[406.632773] [091/132] (614s left) kms_frontbuffer_tracking (psr-modesetfrombusy)
[406.738267] Starting subtest: psr-modesetfrombusy
[412.860067] Subtest psr-modesetfrombusy: SUCCESS (6.128s)
[413.040124] [092/132] (608s left) kms_frontbuffer_tracking (fbc-2p-shrfb-fliptrack-mmap-gtt)
[413.139809] Starting subtest: fbc-2p-shrfb-fliptrack-mmap-gtt
[413.143231] Subtest fbc-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[413.278389] [093/132] (608s left) gem_softpin (allocator-basic)
[413.332917] Starting subtest: allocator-basic
[413.397392] Subtest allocator-basic: SUCCESS (0.064s)
[413.540296] [094/132] (607s left) gem_render_copy (linear-to-vebox-y-tiled)
[413.594205] Starting subtest: linear-to-vebox-y-tiled
[413.597777] Subtest linear-to-vebox-y-tiled: SKIP (0.000s)
[413.786760] [095/132] (607s left) gem_mmap_gtt (hang)
[413.835837] Subtest hang: SKIP (0.000s)
[413.903147] [096/132] (607s left) gem_exec_schedule (out-order)
[413.980419] Starting subtest: out-order
[413.982733] Starting dynamic subtest: rcs0
[414.072737] Dynamic subtest rcs0: SUCCESS (0.100s)
[414.085922] Starting dynamic subtest: vcs0
[414.151895] Dynamic subtest vcs0: SUCCESS (0.079s)
[414.163397] Starting dynamic subtest: vcs1
[414.239445] Dynamic subtest vcs1: SUCCESS (0.087s)
[414.250817] Starting dynamic subtest: vecs0
[414.342161] Dynamic subtest vecs0: SUCCESS (0.102s)
[414.352857] Starting dynamic subtest: ccs0
[414.431507] Dynamic subtest ccs0: SUCCESS (0.087s)
[414.434221] Subtest out-order: SUCCESS (0.461s)
[415.880979] [097/132] (605s left) gem_exec_reloc (basic-scanout)
[415.957977] Subtest basic-scanout: SKIP (0.000s)
[416.046619] [098/132] (605s left) kms_psr (fbc-psr-primary-render)
[416.149528] Starting subtest: fbc-psr-primary-render
[416.151836] Starting dynamic subtest: eDP-1
[416.156080] Dynamic subtest eDP-1: SKIP (0.000s)
[416.160283] Subtest fbc-psr-primary-render: SKIP (0.001s)
[416.244941] [099/132] (605s left) prime_mmap (test_correct_cpu_write)
[416.293879] Starting subtest: test_correct_cpu_write
[416.296205] Starting dynamic subtest: test_correct_cpu_write-smem
[416.298348] Dynamic subtest test_correct_cpu_write-smem: SUCCESS (0.000s)
[416.300541] Subtest test_correct_cpu_write: SUCCESS (0.001s)
[416.426871] [100/132] (605s left) syncobj_timeline (invalid-signal-zero-handles)
[416.477981] Starting subtest: invalid-signal-zero-handles
[416.480258] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[416.598508] [101/132] (605s left) kms_cursor_legacy (torture-bo)
[416.718212] Starting subtest: torture-bo
[416.720491] Starting dynamic subtest: pipe-A
[423.403589] Dynamic subtest pipe-A: SUCCESS (6.633s)
[423.415371] Starting dynamic subtest: pipe-B
[430.417754] Dynamic subtest pipe-B: SUCCESS (7.066s)
[430.429905] Starting dynamic subtest: pipe-C
[436.847711] Dynamic subtest pipe-C: SUCCESS (6.432s)
[436.859347] Starting dynamic subtest: pipe-D
[443.253453] Dynamic subtest pipe-D: SUCCESS (6.405s)
[443.269509] Starting dynamic subtest: all-pipes
[450.024492] Dynamic subtest all-pipes: SUCCESS (6.770s)
[450.027422] Subtest torture-bo: SUCCESS (33.318s)
[450.208263] [102/132] (571s left) kms_cursor_crc (cursor-onscreen-32x10)
[450.365327] Starting subtest: cursor-onscreen-32x10
[450.411349] Subtest cursor-onscreen-32x10: SKIP (0.042s)
[450.695053] [103/132] (571s left) gem_mmap_offset (partial-mmap)
[450.740553] Starting subtest: partial-mmap
[450.742862] Starting dynamic subtest: smem0
[450.745006] Dynamic subtest smem0: SUCCESS (0.003s)
[450.747256] Subtest partial-mmap: SUCCESS (0.004s)
[450.879873] [104/132] (570s left) kms_color_pipeline (plane-lut1d-ctm3x4-lut1d)
[450.958761] Starting subtest: plane-lut1d-ctm3x4-lut1d
[450.971055] Starting dynamic subtest: pipe-A-eDP-1
[451.602502] Dynamic subtest pipe-A-eDP-1: FAIL (0.303s)
[452.323912] Starting dynamic subtest: pipe-B-eDP-1
[453.164739] Dynamic subtest pipe-B-eDP-1: FAIL (0.313s)
[453.653368] Starting dynamic subtest: pipe-C-eDP-1
[454.491086] Dynamic subtest pipe-C-eDP-1: FAIL (0.246s)
[454.981705] Starting dynamic subtest: pipe-D-eDP-1
[455.804100] Dynamic subtest pipe-D-eDP-1: FAIL (0.246s)
[455.806041] Subtest plane-lut1d-ctm3x4-lut1d: FAIL (4.561s)
[456.364324] [105/132] (565s left) gem_userptr_blits (unsync-overlap)
[456.436863] Subtest unsync-overlap: SKIP (0.000s)
[456.593115] [106/132] (565s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[456.706051] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip
[456.710298] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[456.843653] [107/132] (565s left) kms_selftest (drm_damage_helper)
[456.884619] Starting subtest: drm_damage_helper
[456.930455] Starting dynamic subtest: drm_test_damage_iter_no_damage
[457.095393] Dynamic subtest drm_test_damage_iter_no_damage: SUCCESS (0.164s)
[457.105819] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src
[457.229863] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src: SUCCESS (0.134s)
[457.240894] Starting dynamic subtest: drm_test_damage_iter_no_damage_src_moved
[457.369818] Dynamic subtest drm_test_damage_iter_no_damage_src_moved: SUCCESS (0.139s)
[457.380328] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src_moved
[457.495105] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src_moved: SUCCESS (0.125s)
[457.506293] Starting dynamic subtest: drm_test_damage_iter_no_damage_not_visible
[457.618675] Dynamic subtest drm_test_damage_iter_no_damage_not_visible: SUCCESS (0.122s)
[457.629687] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_crtc
[457.733554] Dynamic subtest drm_test_damage_iter_no_damage_no_crtc: SUCCESS (0.115s)
[457.744719] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_fb
[457.871464] Dynamic subtest drm_test_damage_iter_no_damage_no_fb: SUCCESS (0.137s)
[457.880540] Starting dynamic subtest: drm_test_damage_iter_simple_damage
[458.004795] Dynamic subtest drm_test_damage_iter_simple_damage: SUCCESS (0.133s)
[458.013277] Starting dynamic subtest: drm_test_damage_iter_single_damage
[458.134070] Dynamic subtest drm_test_damage_iter_single_damage: SUCCESS (0.129s)
[458.142712] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_src
[458.254343] Dynamic subtest drm_test_damage_iter_single_damage_intersect_src: SUCCESS (0.119s)
[458.264707] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_src
[458.394498] Dynamic subtest drm_test_damage_iter_single_damage_outside_src: SUCCESS (0.139s)
[458.404737] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src
[458.520550] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src: SUCCESS (0.126s)
[458.529185] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_fractional_src
[458.605401] Dynamic subtest drm_test_damage_iter_single_damage_intersect_fractional_src: SUCCESS (0.085s)
[458.615399] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_fractional_src
[458.733895] Dynamic subtest drm_test_damage_iter_single_damage_outside_fractional_src: SUCCESS (0.127s)
[458.742887] Starting dynamic subtest: drm_test_damage_iter_single_damage_src_moved
[458.862285] Dynamic subtest drm_test_damage_iter_single_damage_src_moved: SUCCESS (0.125s)
[458.871341] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src_moved
[458.993201] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src_moved: SUCCESS (0.130s)
[459.001695] Starting dynamic subtest: drm_test_damage_iter_damage
[459.093446] Dynamic subtest drm_test_damage_iter_damage: SUCCESS (0.100s)
[459.103992] Starting dynamic subtest: drm_test_damage_iter_damage_one_intersect
[459.182896] Dynamic subtest drm_test_damage_iter_damage_one_intersect: SUCCESS (0.090s)
[459.191738] Starting dynamic subtest: drm_test_damage_iter_damage_one_outside
[459.317796] Dynamic subtest drm_test_damage_iter_damage_one_outside: SUCCESS (0.131s)
[459.328478] Starting dynamic subtest: drm_test_damage_iter_damage_src_moved
[459.459721] Dynamic subtest drm_test_damage_iter_damage_src_moved: SUCCESS (0.138s)
[459.470518] Starting dynamic subtest: drm_test_damage_iter_damage_not_visible
[459.606700] Dynamic subtest drm_test_damage_iter_damage_not_visible: SUCCESS (0.147s)
[459.608886] Subtest drm_damage_helper: SUCCESS (2.721s)
[459.808330] [108/132] (562s left) gem_exec_capture (capture-recoverable)
[459.878834] Starting subtest: capture-recoverable
[459.883265] Subtest capture-recoverable: SUCCESS (0.005s)
[460.028505] [109/132] (562s left) gem_tiled_swapping (non-threaded)
[460.090406] Subtest non-threaded: SKIP (0.000s)
[460.156634] [110/132] (561s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs-cc)
[460.270254] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs-cc
[460.272130] Starting dynamic subtest: pipe-A-eDP-1
[461.117351] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.842s)
[461.128535] Starting dynamic subtest: pipe-B-eDP-1
[462.771561] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.651s)
[462.782203] Starting dynamic subtest: pipe-C-eDP-1
[464.267493] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.494s)
[464.277968] Starting dynamic subtest: pipe-D-eDP-1
[465.808821] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.538s)
[465.811130] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (5.531s)
[467.024792] [111/132] (555s left) gem_exec_balancer (full-pulse)
[467.096109] Starting subtest: full-pulse
[471.607695] Subtest full-pulse: SUCCESS (4.507s)
[471.950991] [112/132] (550s left) kms_frontbuffer_tracking (fbc-rgb565-draw-render)
[472.065395] Starting subtest: fbc-rgb565-draw-render
[476.290323] Subtest fbc-rgb565-draw-render: SUCCESS (4.225s)
[477.507844] [113/132] (544s left) kms_flip (flip-vs-panning-vs-hang)
[477.604363] Starting subtest: flip-vs-panning-vs-hang
[477.606419] Starting dynamic subtest: A-eDP1
[517.229507] Dynamic subtest A-eDP1: SUCCESS (39.618s)
[517.238798] Starting dynamic subtest: D-eDP1
[557.225712] Dynamic subtest D-eDP1: SUCCESS (40.003s)
[557.228258] Subtest flip-vs-panning-vs-hang: SUCCESS (79.624s)
[558.488689] [114/132] (463s left) kms_addfb_basic (clobberred-modifier)
[558.561058] Starting subtest: clobberred-modifier
[558.565414] Subtest clobberred-modifier: SKIP (0.000s)
[558.714052] [115/132] (463s left) gem_lmem_swapping (smem-oom)
[558.767328] Subtest smem-oom: SKIP (0.000s)
[558.811527] [116/132] (463s left) kms_plane_alpha_blend (coverage-vs-premult-vs-constant)
[558.902814] Starting subtest: coverage-vs-premult-vs-constant
[559.182848] Starting dynamic subtest: pipe-A-eDP-1
[559.725810] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.540s)
[559.735783] Starting dynamic subtest: pipe-D-eDP-1
[561.086228] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.361s)
[561.088094] Subtest coverage-vs-premult-vs-constant: SUCCESS (2.185s)
[561.939799] [117/132] (460s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-plflip-blt)
[562.047338] Starting subtest: psr-2p-scndscrn-indfb-plflip-blt
[562.051489] Subtest psr-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[562.170260] [118/132] (460s left) syncobj_timeline (etime-single-wait-for-submit-submitted)
[562.224677] Starting subtest: etime-single-wait-for-submit-submitted
[562.331706] Subtest etime-single-wait-for-submit-submitted: SUCCESS (0.106s)
[562.420036] [119/132] (460s left) gem_softpin (noreloc)
[562.477228] Starting subtest: noreloc
[567.963940] Subtest noreloc: SUCCESS (5.425s)
[568.068837] [120/132] (454s left) gem_render_tiled_blits (basic)
[568.132723] Subtest basic: SKIP (0.000s)
[568.221785] [121/132] (454s left) kms_psr (pr-suspend)
[568.314504] Starting subtest: pr-suspend
[568.318948] Subtest pr-suspend: SKIP (0.000s)
[568.402761] [122/132] (454s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted-signal
[568.457852] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted-signaled
[568.564314] Subtest etime-multi-wait-all-available-unsubmitted-submitted-signaled: SUCCESS (0.105s)
[568.655777] [123/132] (454s left) kms_psr (psr-cursor-blt)
[568.749932] Starting subtest: psr-cursor-blt
[568.752276] Starting dynamic subtest: eDP-1
[570.516259] Dynamic subtest eDP-1: SUCCESS (1.766s)
[570.518441] Subtest psr-cursor-blt: SUCCESS (1.767s)
[571.403076] [124/132] (451s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-gtt)
[571.509660] Starting subtest: fbc-rgb565-draw-mmap-gtt
[571.513469] Subtest fbc-rgb565-draw-mmap-gtt: SKIP (0.000s)
[571.636867] [125/132] (451s left) gem_exec_params (sol-reset-not-gen7)
[571.699901] Starting subtest: sol-reset-not-gen7
[571.702240] Subtest sol-reset-not-gen7: SUCCESS (0.005s)
[571.844988] [126/132] (450s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-wc)
[571.944088] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-wc
[571.948016] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[572.069811] [127/132] (450s left) kms_plane (planar-pixel-format-settings)
[572.152429] Starting subtest: planar-pixel-format-settings
[572.217536] Subtest planar-pixel-format-settings: SUCCESS (0.067s)
[572.368032] [128/132] (450s left) gem_exec_whisper (basic-fds-priority-all)
[572.444748] Starting subtest: basic-fds-priority-all
[594.337256] Subtest basic-fds-priority-all: SUCCESS (21.892s)
[594.530801] [129/132] (428s left) syncobj_wait (invalid-single-wait-all-unsubmitted)
[594.589982] Starting subtest: invalid-single-wait-all-unsubmitted
[594.591865] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[594.715033] [130/132] (428s left) kms_flip (2x-flip-vs-expired-vblank)
[594.810748] Starting subtest: 2x-flip-vs-expired-vblank
[594.821970] Subtest 2x-flip-vs-expired-vblank: SKIP (0.010s)
[594.953011] [131/132] (428s left) gem_exec_flush (basic-batch-kernel-default-uc)
[595.015287] Starting subtest: basic-batch-kernel-default-uc
[602.302688] Subtest basic-batch-kernel-default-uc: SUCCESS (7.286s)
[602.517123] [132/132] (420s left) kms_frontbuffer_tracking (fbcpsr-modesetfrombusy)
[602.626089] Starting subtest: fbcpsr-modesetfrombusy
[608.849803] Subtest fbcpsr-modesetfrombusy: SUCCESS (6.229s)
[609.034958] Closing watchdogs
results: parsing output: 0/ for test: kms_big_fb
results: parsing output: 1/ for test: kms_psr2_sf
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_cursor_crc
results: parsing output: 4/ for test: syncobj_wait
results: parsing output: 5/ for test: gem_ctx_param
results: parsing output: 6/ for test: gem_render_copy
results: parsing output: 7/ for test: i915_query
results: parsing output: 8/ for test: kms_flip_scaled_crc
results: parsing output: 9/ for test: kms_plane_scaling
results: parsing output: 10/ for test: kms_psr
results: parsing output: 11/ for test: kms_pm_lpsp
results: parsing output: 12/ for test: syncobj_timeline
results: parsing output: 13/ for test: kms_atomic_interruptible
results: parsing output: 14/ for test: api_intel_allocator
results: parsing output: 15/ for test: kms_joiner
results: parsing output: 16/ for test: kms_getfb
results: parsing output: 17/ for test: kms_fbcon_fbt
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: kms_chamelium_hpd
results: parsing output: 20/ for test: gem_exec_whisper
results: parsing output: 21/ for test: kms_big_fb
results: parsing output: 22/ for test: kms_psr
results: parsing output: 23/ for test: kms_big_fb
results: parsing output: 24/ for test: perf_pmu
results: parsing output: 25/ for test: kms_legacy_colorkey
results: parsing output: 26/ for test: kms_vblank
results: parsing output: 27/ for test: syncobj_eventfd
results: parsing output: 28/ for test: gem_mmap_gtt
results: parsing output: 29/ for test: kms_cursor_legacy
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: gem_exec_fence
results: parsing output: 32/ for test: gem_mmap_gtt
results: parsing output: 33/ for test: kms_atomic
results: parsing output: 34/ for test: kms_frontbuffer_tracking
results: parsing output: 35/ for test: kms_cursor_legacy
results: parsing output: 36/ for test: kms_vrr
results: parsing output: 37/ for test: kms_content_protection
results: parsing output: 38/ for test: kms_flip_scaled_crc
results: parsing output: 39/ for test: i915_pm_rpm
results: parsing output: 40/ for test: kms_big_fb
results: parsing output: 41/ for test: gem_mmap_gtt
results: parsing output: 42/ for test: gem_ctx_shared
results: parsing output: 43/ for test: syncobj_timeline
results: parsing output: 44/ for test: kms_async_flips
results: parsing output: 45/ for test: gem_ctx_create
results: parsing output: 46/ for test: gem_tiling_max_stride
results: parsing output: 47/ for test: i915_drm_fdinfo
results: parsing output: 48/ for test: kms_chamelium_edid
results: parsing output: 49/ for test: gem_ctx_persistence
results: parsing output: 50/ for test: kms_ccs
results: parsing output: 51/ for test: kms_flip
results: parsing output: 52/ for test: core_setmaster
results: parsing output: 53/ for test: api_intel_bb
results: parsing output: 54/ for test: gem_exec_suspend
results: parsing output: 55/ for test: gem_exec_reloc
results: parsing output: 56/ for test: syncobj_wait
results: parsing output: 57/ for test: gem_exec_schedule
results: parsing output: 58/ for test: kms_flip
results: parsing output: 59/ for test: gem_pxp
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: kms_psr2_sf
results: parsing output: 62/ for test: gem_ctx_persistence
results: parsing output: 63/ for test: gem_ccs
results: parsing output: 64/ for test: gem_eio
results: parsing output: 65/ for test: kms_pipe_b_c_ivb
results: parsing output: 66/ for test: kms_flip
results: parsing output: 67/ for test: i915_drm_fdinfo
results: parsing output: 68/ for test: kms_plane
results: parsing output: 69/ for test: kms_psr_stress_test
results: parsing output: 70/ for test: kms_pm_rpm
results: parsing output: 71/ for test: gem_ccs
results: parsing output: 72/ for test: kms_big_fb
results: parsing output: 73/ for test: kms_plane_lowres
results: parsing output: 74/ for test: gem_close_race
results: parsing output: 75/ for test: kms_getfb
results: parsing output: 76/ for test: kms_feature_discovery
results: parsing output: 77/ for test: kms_chamelium_color
results: parsing output: 78/ for test: kms_big_fb
results: parsing output: 79/ for test: kms_cursor_legacy
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: gem_wait
results: parsing output: 83/ for test: kms_ccs
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: kms_ccs
results: parsing output: 87/ for test: gen9_exec_parse
results: parsing output: 88/ for test: perf
results: parsing output: 89/ for test: sw_sync
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: gem_softpin
results: parsing output: 93/ for test: gem_render_copy
results: parsing output: 94/ for test: gem_mmap_gtt
results: parsing output: 95/ for test: gem_exec_schedule
results: parsing output: 96/ for test: gem_exec_reloc
results: parsing output: 97/ for test: kms_psr
results: parsing output: 98/ for test: prime_mmap
results: parsing output: 99/ for test: syncobj_timeline
results: parsing output: 100/ for test: kms_cursor_legacy
results: parsing output: 101/ for test: kms_cursor_crc
results: parsing output: 102/ for test: gem_mmap_offset
results: parsing output: 103/ for test: kms_color_pipeline
results: parsing output: 104/ for test: gem_userptr_blits
results: parsing output: 105/ for test: kms_big_fb
results: parsing output: 106/ for test: kms_selftest
results: parsing output: 107/ for test: gem_exec_capture
results: parsing output: 108/ for test: gem_tiled_swapping
results: parsing output: 109/ for test: kms_ccs
results: parsing output: 110/ for test: gem_exec_balancer
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: kms_flip
results: parsing output: 113/ for test: kms_addfb_basic
results: parsing output: 114/ for test: gem_lmem_swapping
results: parsing output: 115/ for test: kms_plane_alpha_blend
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: syncobj_timeline
results: parsing output: 118/ for test: gem_softpin
results: parsing output: 119/ for test: gem_render_tiled_blits
results: parsing output: 120/ for test: kms_psr
results: parsing output: 121/ for test: syncobj_timeline
results: parsing output: 122/ for test: kms_psr
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: gem_exec_params
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: kms_plane
results: parsing output: 127/ for test: gem_exec_whisper
results: parsing output: 128/ for test: syncobj_wait
results: parsing output: 129/ for test: kms_flip
results: parsing output: 130/ for test: gem_exec_flush
results: parsing output: 131/ for test: kms_frontbuffer_tracking
Done.