[61.159402] Initializing watchdogs
[61.159449]   /dev/watchdog0
[61.173726] [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]
[61.178148] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[61.180356] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[61.184449] [001/133] (960s left) sw_sync (timeline_closed)
[61.244857] Starting subtest: timeline_closed
[61.247529] Subtest timeline_closed: SUCCESS (0.000s)
[61.298099] [002/133] (960s left) gem_busy (close-race)
[61.359233] Starting subtest: close-race
[86.933679] Subtest close-race: SUCCESS (25.553s)
[87.326593] [FACT gem_busy (close-race)] new: kernel.kmod_is_loaded.vgem: true
[87.331779] [003/133] (934s left) i915_query (query-topology-garbage-items)
[87.425569] Starting subtest: query-topology-garbage-items
[87.427856] Subtest query-topology-garbage-items: SUCCESS (0.000s)
[87.523983] [004/133] (934s left) gem_userptr_blits (dmabuf-unsync)
[87.589206] Subtest dmabuf-unsync: SKIP (0.000s)
[87.695235] [005/133] (934s left) perf (gen8-unprivileged-single-ctx-counters)
[87.838201] Starting subtest: gen8-unprivileged-single-ctx-counters
[87.842189] Subtest gen8-unprivileged-single-ctx-counters: SKIP (0.000s)
[87.892123] [006/133] (933s left) kms_flip (flip-vs-dpms-off-vs-modeset)
[88.002049] Starting subtest: flip-vs-dpms-off-vs-modeset
[88.004434] Starting dynamic subtest: A-eDP1
[91.658644] Dynamic subtest A-eDP1: SUCCESS (3.653s)
[91.669798] Starting dynamic subtest: B-eDP1
[94.853623] Dynamic subtest B-eDP1: SUCCESS (3.193s)
[94.862513] Starting dynamic subtest: C-eDP1
[97.945319] Dynamic subtest C-eDP1: SUCCESS (3.091s)
[97.953636] Starting dynamic subtest: D-eDP1
[101.066317] Dynamic subtest D-eDP1: SUCCESS (3.123s)
[101.068679] Subtest flip-vs-dpms-off-vs-modeset: SUCCESS (13.066s)
[101.879093] [007/133] (920s left) kms_feature_discovery (chamelium)
[101.963743] Starting subtest: chamelium
[101.968219] Subtest chamelium: SKIP (0.000s)
[102.038922] [008/133] (919s left) kms_atomic_transition (plane-all-modeset-transition)
[102.138916] Starting subtest: plane-all-modeset-transition
[102.143839] Subtest plane-all-modeset-transition: SKIP (0.000s)
[102.208605] [009/133] (919s left) gem_tiled_partial_pwrite_pread (reads)
[102.273497] Subtest reads: SKIP (0.000s)
[102.345157] [010/133] (919s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping)
[102.436801] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping
[102.441066] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping: SKIP (0.000s)
[102.521946] [011/133] (919s left) gem_mmap_offset (oob-read)
[102.583492] Starting subtest: oob-read
[102.585493] Subtest oob-read: SUCCESS (0.000s)
[102.695058] [012/133] (919s left) kms_ccs (random-ccs-data-y-tiled-gen12-mc-ccs)
[102.787480] Starting subtest: random-ccs-data-y-tiled-gen12-mc-ccs
[102.789965] Starting dynamic subtest: pipe-A-eDP-1
[102.813080] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[102.821345] Starting dynamic subtest: pipe-B-eDP-1
[102.840850] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[102.847886] Starting dynamic subtest: pipe-C-eDP-1
[102.867495] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[102.874401] Starting dynamic subtest: pipe-D-eDP-1
[102.893999] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[102.897343] Subtest random-ccs-data-y-tiled-gen12-mc-ccs: SKIP (0.003s)
[105.040757] [013/133] (917s left) syncobj_timeline (multi-wait-available-submitted-signaled)
[105.094478] Starting subtest: multi-wait-available-submitted-signaled
[105.096651] Subtest multi-wait-available-submitted-signaled: SUCCESS (0.001s)
[105.185819] [014/133] (917s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-sf)
[105.285888] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-sf
[105.290120] Subtest fbc-pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.000s)
[105.350546] [015/133] (916s left) gem_mmap_wc (read)
[105.426184] Subtest read: SKIP (0.000s)
[105.494957] [016/133] (916s left) kms_big_fb (linear-16bpp-rotate-180)
[105.590475] Starting subtest: linear-16bpp-rotate-180
[106.946409] Subtest linear-16bpp-rotate-180: SUCCESS (1.356s)
[107.065578] [017/133] (915s left) kms_psr (fbc-psr2-sprite-render)
[107.162599] Starting subtest: fbc-psr2-sprite-render
[107.164533] Starting dynamic subtest: eDP-1
[107.167939] Dynamic subtest eDP-1: SKIP (0.000s)
[107.171419] Subtest fbc-psr2-sprite-render: SKIP (0.001s)
[107.234071] [018/133] (915s left) gem_eio (in-flight-external)
[107.462793] Starting subtest: in-flight-external
[108.952105] Subtest in-flight-external: SUCCESS (1.488s)
[109.085391] [019/133] (913s left) kms_cursor_crc (cursor-rapid-movement-256x256)
[109.331837] Starting subtest: cursor-rapid-movement-256x256
[109.334180] Starting dynamic subtest: pipe-A-eDP-1
[109.904826] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.570s)
[109.933254] Starting dynamic subtest: pipe-D-eDP-1
[111.133720] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.206s)
[111.136714] Subtest cursor-rapid-movement-256x256: SUCCESS (1.802s)
[112.005588] [020/133] (910s left) kms_busy (extended-modeset-hang-oldfb-with-reset)
[112.110340] Starting subtest: extended-modeset-hang-oldfb-with-reset
[112.112562] Starting dynamic subtest: pipe-A
[123.862815] Dynamic subtest pipe-A: SUCCESS (11.752s)
[123.872764] Starting dynamic subtest: pipe-D
[136.122294] Dynamic subtest pipe-D: SUCCESS (12.256s)
[136.124664] Subtest extended-modeset-hang-oldfb-with-reset: SUCCESS (24.011s)
[136.917968] [021/133] (885s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs)
[137.020480] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs
[137.022422] Starting dynamic subtest: pipe-A-eDP-1
[137.911017] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.884s)
[137.920578] Starting dynamic subtest: pipe-B-eDP-1
[139.540439] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.626s)
[139.549290] Starting dynamic subtest: pipe-C-eDP-1
[141.081732] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.542s)
[141.090832] Starting dynamic subtest: pipe-D-eDP-1
[142.574590] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.491s)
[142.576539] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SUCCESS (5.547s)
[143.747936] [022/133] (878s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[143.848574] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[143.852706] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[143.941332] [023/133] (878s left) kms_big_fb (yf-tiled-addfb)
[144.034541] Starting subtest: yf-tiled-addfb
[144.038746] Subtest yf-tiled-addfb: SKIP (0.000s)
[144.136247] [024/133] (878s left) gem_close_race (multigpu-basic-threads)
[144.211184] Starting subtest: multigpu-basic-threads
[144.215425] Subtest multigpu-basic-threads: SKIP (0.000s)
[144.435296] [025/133] (878s left) api_intel_allocator (two-level-inception-interruptible)
[144.496597] Starting subtest: two-level-inception-interruptible
[149.903077] Subtest two-level-inception-interruptible: SUCCESS (5.409s)
[150.049617] [026/133] (872s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-plflip-blt)
[150.165205] Starting subtest: fbcpsr-1p-primscrn-shrfb-plflip-blt
[157.662586] Subtest fbcpsr-1p-primscrn-shrfb-plflip-blt: SUCCESS (7.502s)
[157.784874] [027/133] (864s left) kms_ccs (crc-primary-basic-y-tiled-gen12-mc-ccs)
[157.882124] Starting subtest: crc-primary-basic-y-tiled-gen12-mc-ccs
[157.884430] Starting dynamic subtest: pipe-A-eDP-1
[157.906667] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[157.917754] Starting dynamic subtest: pipe-B-eDP-1
[157.938052] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[157.946079] Starting dynamic subtest: pipe-C-eDP-1
[157.966368] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[157.976309] Starting dynamic subtest: pipe-D-eDP-1
[157.997782] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[158.002106] Subtest crc-primary-basic-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[160.029132] [028/133] (862s left) gem_mmap_wc (copy)
[160.087281] Subtest copy: SKIP (0.000s)
[160.148971] [029/133] (862s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions-varying-s
[160.276363] Starting subtest: short-busy-flip-before-cursor-atomic-transitions-varying-size
[160.280900] Subtest short-busy-flip-before-cursor-atomic-transitions-varying-size: SKIP (0.000s)
[160.431953] [030/133] (862s left) kms_big_fb (x-tiled-16bpp-rotate-90)
[160.527931] Starting subtest: x-tiled-16bpp-rotate-90
[160.547715] Subtest x-tiled-16bpp-rotate-90: SKIP (0.020s)
[160.677536] [031/133] (862s left) kms_cursor_crc (cursor-sliding-256x85)
[160.961888] Starting subtest: cursor-sliding-256x85
[161.011802] Subtest cursor-sliding-256x85: SKIP (0.047s)
[161.206853] [032/133] (861s left) gem_exec_fence (syncobj-invalid-flags)
[161.660854] Starting subtest: syncobj-invalid-flags
[161.663245] Subtest syncobj-invalid-flags: SUCCESS (0.000s)
[162.995375] [033/133] (859s left) kms_flip (dpms-vs-vblank-race-interruptible)
[163.103489] Starting subtest: dpms-vs-vblank-race-interruptible
[163.105866] Starting dynamic subtest: A-eDP1
[166.905222] Dynamic subtest A-eDP1: SUCCESS (3.800s)
[166.914399] Starting dynamic subtest: B-eDP1
[171.527668] Dynamic subtest B-eDP1: SUCCESS (4.619s)
[171.536663] Starting dynamic subtest: C-eDP1
[176.151967] Dynamic subtest C-eDP1: SUCCESS (4.620s)
[176.160555] Starting dynamic subtest: D-eDP1
[180.753650] Dynamic subtest D-eDP1: SUCCESS (4.599s)
[180.756051] Subtest dpms-vs-vblank-race-interruptible: SUCCESS (17.652s)
[181.638441] [034/133] (841s left) kms_addfb_basic (basic-y-tiled-legacy)
[181.690902] Starting subtest: basic-y-tiled-legacy
[181.694744] Subtest basic-y-tiled-legacy: SKIP (0.000s)
[181.821620] [035/133] (841s left) gem_lmem_swapping (heavy-verify-multi)
[181.868089] Subtest heavy-verify-multi: SKIP (0.000s)
[181.916691] [036/133] (841s left) api_intel_bb (misplaced-blitter)
[181.971839] Starting subtest: misplaced-blitter
[181.977767] Subtest misplaced-blitter: SUCCESS (0.008s)
[182.086470] [037/133] (841s left) kms_vblank (query-idle-hang)
[182.186154] Starting subtest: query-idle-hang
[182.188357] Starting dynamic subtest: pipe-A-eDP-1
[208.671881] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.483s)
[208.685574] Starting dynamic subtest: pipe-D-eDP-1
[223.638341] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.965s)
[223.640255] Subtest query-idle-hang: SUCCESS (41.452s)
[224.505370] [038/133] (798s left) kms_fbcon_fbt (psr-suspend)
[224.581740] Starting subtest: psr-suspend
[273.380028] Subtest psr-suspend: SUCCESS (19.794s)
[273.453086] [039/133] (749s left) kms_cursor_crc (cursor-offscreen-128x42)
[273.691475] Starting subtest: cursor-offscreen-128x42
[273.740135] Subtest cursor-offscreen-128x42: SKIP (0.044s)
[273.956772] [040/133] (749s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[274.060006] Starting subtest: bad-pixel-format-y-tiled-ccs
[274.062362] Starting dynamic subtest: pipe-A-eDP-1
[274.085140] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[274.100124] Starting dynamic subtest: pipe-B-eDP-1
[274.121964] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[274.132907] Starting dynamic subtest: pipe-C-eDP-1
[274.155051] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[274.163662] Starting dynamic subtest: pipe-D-eDP-1
[274.186187] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[274.191157] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.004s)
[276.394033] [041/133] (746s left) kms_atomic_interruptible (universal-setplane-cursor)
[276.493977] Starting subtest: universal-setplane-cursor
[276.496298] Starting dynamic subtest: pipe-A-eDP-1
[283.919101] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.393s)
[283.921019] Subtest universal-setplane-cursor: SUCCESS (7.395s)
[284.791427] [042/133] (738s left) gem_lmem_swapping (verify-ccs)
[284.835923] Subtest verify-ccs: SKIP (0.000s)
[284.888065] [043/133] (738s left) gem_workarounds (reset-context)
[284.952998] Starting subtest: reset-context
[285.117208] Subtest reset-context: SUCCESS (0.164s)
[285.252239] [044/133] (738s left) kms_chamelium_edid (dp-edid-stress-resolution-4k)
[285.631889] Subtest dp-edid-stress-resolution-4k: SKIP (0.000s)
[286.395822] [045/133] (737s left) syncobj_timeline (invalid-multi-wait-unsubmitted-signaled)
[286.453537] Starting subtest: invalid-multi-wait-unsubmitted-signaled
[286.455959] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.000s)
[286.553579] [046/133] (736s left) gem_exec_reloc (basic-write-wc-noreloc)
[286.610690] Subtest basic-write-wc-noreloc: SKIP (0.000s)
[286.675669] [047/133] (736s left) gem_userptr_blits (coherency-unsync)
[286.740047] Subtest coherency-unsync: SKIP (0.000s)
[286.852446] [048/133] (736s left) kms_universal_plane (universal-plane-functional)
[286.940779] Starting subtest: universal-plane-functional
[286.943013] Starting dynamic subtest: pipe-A-eDP-1
[290.905275] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.957s)
[290.916142] Starting dynamic subtest: pipe-B-eDP-1
[295.583774] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.672s)
[295.595228] Starting dynamic subtest: pipe-C-eDP-1
[299.933977] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.345s)
[299.944171] Starting dynamic subtest: pipe-D-eDP-1
[304.327896] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.393s)
[304.330098] Subtest universal-plane-functional: SUCCESS (17.373s)
[305.194154] [049/133] (718s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[305.306658] Starting subtest: 4-tiled-32bpp-rotate-180
[306.718513] Subtest 4-tiled-32bpp-rotate-180: SUCCESS (1.412s)
[306.866254] [050/133] (716s left) gem_ctx_persistence (engines-persistence)
[306.926782] Starting subtest: engines-persistence
[306.928718] Starting dynamic subtest: rcs0
[307.730516] Dynamic subtest rcs0: SUCCESS (0.803s)
[307.742037] Starting dynamic subtest: bcs0
[308.468060] Dynamic subtest bcs0: SUCCESS (0.735s)
[308.479668] Starting dynamic subtest: vcs0
[309.236416] Dynamic subtest vcs0: SUCCESS (0.767s)
[309.250116] Starting dynamic subtest: vcs1
[310.024555] Dynamic subtest vcs1: SUCCESS (0.786s)
[310.040853] Starting dynamic subtest: vecs0
[310.798913] Dynamic subtest vecs0: SUCCESS (0.772s)
[310.813136] Starting dynamic subtest: ccs0
[311.539382] Dynamic subtest ccs0: SUCCESS (0.739s)
[311.541792] Subtest engines-persistence: SUCCESS (4.614s)
[311.657421] [051/133] (712s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-render)
[311.763279] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-render
[318.708121] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-render: SUCCESS (6.946s)
[318.859730] [052/133] (704s left) kms_color (ctm-negative)
[318.958792] Starting subtest: ctm-negative
[318.960790] Starting dynamic subtest: pipe-A-eDP-1
[319.678301] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.722s)
[319.690162] Starting dynamic subtest: pipe-B-eDP-1
[321.146640] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.467s)
[321.156690] Starting dynamic subtest: pipe-C-eDP-1
[322.518732] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.372s)
[322.528615] Starting dynamic subtest: pipe-D-eDP-1
[323.855911] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.335s)
[323.858164] Subtest ctm-negative: SUCCESS (4.899s)
[324.657700] [053/133] (699s left) gem_exec_capture (capture)
[324.725222] Starting subtest: capture
[324.727614] Starting dynamic subtest: rcs0-smem
[325.547998] Dynamic subtest rcs0-smem: SUCCESS (0.822s)
[325.559376] Starting dynamic subtest: bcs0-smem
[326.369387] Dynamic subtest bcs0-smem: SUCCESS (0.820s)
[326.380169] Starting dynamic subtest: vcs0-smem
[327.176165] Dynamic subtest vcs0-smem: SUCCESS (0.806s)
[327.186770] Starting dynamic subtest: vcs1-smem
[328.021816] Dynamic subtest vcs1-smem: SUCCESS (0.848s)
[328.033448] Starting dynamic subtest: vecs0-smem
[328.914361] Dynamic subtest vecs0-smem: FAIL (0.830s)
[328.925536] Starting dynamic subtest: ccs0-smem
[330.180528] Dynamic subtest ccs0-smem: SUCCESS (1.325s)
[330.183276] Subtest capture: FAIL (5.455s)
[330.297187] [054/133] (693s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[330.404172] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[330.408270] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[330.506997] [055/133] (693s left) gem_render_copy (yf-tiled-ccs-to-y-tiled-ccs)
[330.575140] Starting subtest: yf-tiled-ccs-to-y-tiled-ccs
[330.579717] Subtest yf-tiled-ccs-to-y-tiled-ccs: SKIP (0.000s)
[330.757429] [056/133] (693s left) perf (blocking)
[330.843690] Starting subtest: blocking
[330.846114] Starting dynamic subtest: 0-rcs0
[340.875942] Dynamic subtest 0-rcs0: SUCCESS (10.028s)
[340.885249] Starting dynamic subtest: 1-vecs0
[350.902211] Dynamic subtest 1-vecs0: SUCCESS (10.021s)
[350.904665] Subtest blocking: SUCCESS (20.052s)
[350.952025] [057/133] (673s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[351.051606] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt
[351.056082] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[351.170483] [058/133] (672s left) gem_exec_whisper (basic-normal)
[351.231981] Starting subtest: basic-normal
[356.157570] Subtest basic-normal: SUCCESS (4.924s)
[356.358646] [059/133] (667s left) prime_busy (before-wait)
[356.423734] Starting subtest: before-wait
[356.426080] Starting dynamic subtest: rcs0
[356.461691] Dynamic subtest rcs0: SUCCESS (0.036s)
[356.473549] Starting dynamic subtest: bcs0
[356.514697] Dynamic subtest bcs0: SUCCESS (0.053s)
[356.524431] Starting dynamic subtest: vcs0
[356.556383] Dynamic subtest vcs0: SUCCESS (0.041s)
[356.566048] Starting dynamic subtest: vcs1
[356.598148] Dynamic subtest vcs1: SUCCESS (0.041s)
[356.608130] Starting dynamic subtest: vecs0
[356.642474] Dynamic subtest vecs0: SUCCESS (0.044s)
[356.652679] Starting dynamic subtest: ccs0
[356.685301] Dynamic subtest ccs0: SUCCESS (0.042s)
[356.794115] Subtest before-wait: SUCCESS (0.371s)
[356.874044] [060/133] (667s left) gem_exec_balancer (bonded-sync)
[356.949825] Starting subtest: bonded-sync
[356.953718] Subtest bonded-sync: SKIP (0.000s)
[357.256515] [061/133] (666s left) gem_mmap_wc (write-read)
[357.326135] Subtest write-read: SKIP (0.000s)
[357.386906] [062/133] (666s left) sysfs_preempt_timeout (timeout)
[357.440751] Starting subtest: timeout
[357.443075] Starting dynamic subtest: vecs0
[366.790558] Dynamic subtest vecs0: SUCCESS (9.343s)
[366.801691] Starting dynamic subtest: ccs0
[368.142992] Dynamic subtest ccs0: SUCCESS (1.350s)
[368.155689] Starting dynamic subtest: vcs0
[377.729781] Dynamic subtest vcs0: SUCCESS (9.589s)
[377.745664] Starting dynamic subtest: bcs0
[378.995128] Dynamic subtest bcs0: SUCCESS (1.264s)
[379.008181] Starting dynamic subtest: rcs0
[380.280236] Dynamic subtest rcs0: SUCCESS (1.284s)
[380.293077] Starting dynamic subtest: vcs1
[389.770178] Dynamic subtest vcs1: SUCCESS (9.486s)
[389.772766] Subtest timeout: SUCCESS (32.328s)
[389.846529] [063/133] (634s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-render)
[389.944195] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-render
[389.948324] Subtest fbc-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[390.039791] [064/133] (634s left) gem_spin_batch (spin-each)
[390.108615] Starting subtest: spin-each
[393.380513] Subtest spin-each: SUCCESS (3.263s)
[393.830724] [065/133] (630s left) dmabuf_sync_file (export-multiwait)
[393.892046] Starting subtest: export-multiwait
[393.894146] Subtest export-multiwait: SUCCESS (0.001s)
[393.956557] [066/133] (630s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[394.020309] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[394.022808] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[394.125544] [067/133] (630s left) gem_pread (exhaustion)
[394.181071] Subtest exhaustion: SKIP (0.000s)
[394.237495] [068/133] (630s left) kms_chamelium_hpd (vga-hpd-fast)
[394.631118] Subtest vga-hpd-fast: SKIP (0.000s)
[395.501584] [069/133] (629s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-plflip-blt)
[395.604879] Starting subtest: psr-1p-primscrn-shrfb-plflip-blt
[403.180831] Subtest psr-1p-primscrn-shrfb-plflip-blt: SUCCESS (7.579s)
[403.316797] [070/133] (621s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-render)
[403.412210] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-render
[403.416239] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[403.520604] [071/133] (621s left) kms_vrr (flipline)
[403.622461] Starting subtest: flipline
[403.628787] Subtest flipline: SKIP (0.000s)
[403.699053] [072/133] (620s left) kms_setmode (invalid-clone-exclusive-crtc)
[403.754399] Starting subtest: invalid-clone-exclusive-crtc
[403.760704] Subtest invalid-clone-exclusive-crtc: SKIP (0.000s)
[403.864124] [073/133] (620s left) i915_hangman (detector)
[403.927031] Starting subtest: detector
[403.929214] Starting dynamic subtest: rcs0
[415.724441] Dynamic subtest rcs0: SUCCESS (11.796s)
[415.737538] Starting dynamic subtest: bcs0
[427.692056] Dynamic subtest bcs0: SUCCESS (11.963s)
[427.704870] Starting dynamic subtest: vcs0
[439.659996] Dynamic subtest vcs0: SUCCESS (11.962s)
[439.672669] Starting dynamic subtest: vcs1
[450.668680] Dynamic subtest vcs1: SUCCESS (11.003s)
[450.681553] Starting dynamic subtest: vecs0
[461.674995] Dynamic subtest vecs0: SUCCESS (11.001s)
[461.687664] Starting dynamic subtest: ccs0
[472.748130] Dynamic subtest ccs0: SUCCESS (11.068s)
[472.750818] Subtest detector: SUCCESS (68.809s)
[472.902605] [074/133] (551s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-downscaling)
[473.000568] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-downscaling
[473.004853] Subtest flip-64bpp-yftile-to-16bpp-yftile-downscaling: SKIP (0.000s)
[473.071872] [075/133] (551s left) gem_exec_reloc (basic-wc-noreloc)
[473.140928] Subtest basic-wc-noreloc: SKIP (0.000s)
[473.217066] [076/133] (551s left) i915_query (query-topology-matches-eu-total)
[473.272636] Starting subtest: query-topology-matches-eu-total
[473.274870] Subtest query-topology-matches-eu-total: SUCCESS (0.000s)
[473.388935] [077/133] (551s left) kms_setmode (invalid-clone-single-crtc)
[473.453227] Starting subtest: invalid-clone-single-crtc
[473.460037] Subtest invalid-clone-single-crtc: SKIP (0.000s)
[473.577786] [078/133] (551s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-msflip-blt)
[473.679368] Starting subtest: psr-2p-primscrn-shrfb-msflip-blt
[473.683769] Subtest psr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[473.781818] [079/133] (551s left) kms_atomic (atomic-invalid-params)
[473.873686] Starting subtest: atomic-invalid-params
[473.875774] Starting dynamic subtest: pipe-A-eDP-1
[474.264373] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.390s)
[474.266508] Subtest atomic-invalid-params: SUCCESS (0.391s)
[475.077935] [080/133] (549s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-downscaling)
[475.176672] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-downscaling
[475.180787] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-downscaling: SKIP (0.000s)
[475.248458] [081/133] (549s left) prime_mmap (test_aperture_limit)
[475.310216] Starting subtest: test_aperture_limit
[475.312588] Starting dynamic subtest: test_aperture_limit-smem
[475.320350] Dynamic subtest test_aperture_limit-smem: SKIP (0.009s)
[475.324838] Subtest test_aperture_limit: SKIP (0.010s)
[475.394115] [082/133] (549s left) gem_userptr_blits (map-fixed-invalidate-overlap-busy)
[475.462930] Starting subtest: map-fixed-invalidate-overlap-busy
[475.467302] Subtest map-fixed-invalidate-overlap-busy: SKIP (0.000s)
[475.590442] [083/133] (549s left) kms_psr (fbc-psr2-cursor-plane-move)
[475.682583] Starting subtest: fbc-psr2-cursor-plane-move
[475.684920] Starting dynamic subtest: eDP-1
[475.688925] Dynamic subtest eDP-1: SKIP (0.000s)
[475.692859] Subtest fbc-psr2-cursor-plane-move: SKIP (0.001s)
[475.770804] [084/133] (549s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs)
[475.858687] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs
[475.860796] Starting dynamic subtest: pipe-A-eDP-1
[476.363979] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.481s)
[476.375330] Starting dynamic subtest: pipe-B-eDP-1
[477.877994] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.509s)
[477.888204] Starting dynamic subtest: pipe-C-eDP-1
[479.277473] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.398s)
[479.288785] Starting dynamic subtest: pipe-D-eDP-1
[480.645028] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.366s)
[480.647280] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SUCCESS (4.759s)
[481.796040] [085/133] (543s left) gem_exec_schedule (submit-early-slice)
[481.867607] Starting subtest: submit-early-slice
[481.869836] Starting dynamic subtest: rcs0
[481.904437] Dynamic subtest rcs0: SUCCESS (0.045s)
[481.916473] Starting dynamic subtest: bcs0
[481.946697] Dynamic subtest bcs0: SUCCESS (0.042s)
[481.957308] Starting dynamic subtest: vcs0
[481.991089] Dynamic subtest vcs0: SUCCESS (0.044s)
[482.001318] Starting dynamic subtest: vcs1
[482.033679] Dynamic subtest vcs1: SUCCESS (0.042s)
[482.045002] Starting dynamic subtest: vecs0
[482.078727] Dynamic subtest vecs0: SUCCESS (0.044s)
[482.090190] Starting dynamic subtest: ccs0
[482.120931] Dynamic subtest ccs0: SUCCESS (0.041s)
[482.123285] Subtest submit-early-slice: SUCCESS (0.262s)
[483.925449] [086/133] (541s left) gem_exec_fence (syncobj-unused-fence)
[484.488519] Starting subtest: syncobj-unused-fence
[484.503238] Subtest syncobj-unused-fence: SUCCESS (0.015s)
[485.790862] [087/133] (539s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-wc)
[485.892961] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-wc
[489.874445] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (3.984s)
[491.003898] [088/133] (534s left) gem_wait (write-wait)
[491.071700] Starting subtest: write-wait
[491.073883] Starting dynamic subtest: all-engines
[492.142272] Dynamic subtest all-engines: SUCCESS (1.072s)
[492.159309] Starting dynamic subtest: rcs0
[493.196610] Dynamic subtest rcs0: SUCCESS (1.052s)
[493.214202] Starting dynamic subtest: bcs0
[494.252851] Dynamic subtest bcs0: SUCCESS (1.054s)
[494.272813] Starting dynamic subtest: vcs0
[495.307689] Dynamic subtest vcs0: SUCCESS (1.052s)
[495.324474] Starting dynamic subtest: vcs1
[496.361443] Dynamic subtest vcs1: SUCCESS (1.051s)
[496.380088] Starting dynamic subtest: vecs0
[497.417421] Dynamic subtest vecs0: SUCCESS (1.054s)
[497.435141] Starting dynamic subtest: ccs0
[498.474777] Dynamic subtest ccs0: SUCCESS (1.056s)
[498.477214] Subtest write-wait: SUCCESS (7.407s)
[498.692071] [089/133] (526s left) kms_getfb (getfb-handle-valid)
[498.805861] Starting subtest: getfb-handle-valid
[498.808188] Subtest getfb-handle-valid: SUCCESS (0.000s)
[498.903028] [090/133] (526s left) kms_psr (pr-cursor-mmap-gtt)
[498.996955] Starting subtest: pr-cursor-mmap-gtt
[499.001086] Subtest pr-cursor-mmap-gtt: SKIP (0.000s)
[499.073533] [091/133] (526s left) syncobj_wait (wait-for-submit-complex)
[499.136268] Starting subtest: wait-for-submit-complex
[499.200312] Subtest wait-for-submit-complex: SUCCESS (0.062s)
[499.289895] [092/133] (526s left) gem_basic (multigpu-create-close)
[499.342342] Starting subtest: multigpu-create-close
[499.346712] Subtest multigpu-create-close: SKIP (0.000s)
[499.445213] [093/133] (526s left) kms_pm_rpm (pc8-residency)
[500.987608] Starting subtest: pc8-residency
[500.992593] Subtest pc8-residency: SKIP (0.000s)
[501.748211] [094/133] (523s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-wc)
[501.845852] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-wc
[505.384849] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (3.545s)
[506.536163] [095/133] (519s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[506.633368] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[507.313293] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SUCCESS (0.681s)
[507.487367] [096/133] (518s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-render)
[507.594877] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-render
[507.599029] Subtest psr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[507.686673] [097/133] (518s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[507.781590] Starting subtest: compare-crc-sanitycheck-xr24
[507.783938] Starting dynamic subtest: pipe-A-eDP-1
[508.458085] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.674s)
[508.469155] Starting dynamic subtest: pipe-B-eDP-1
[509.696808] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.237s)
[509.707979] Starting dynamic subtest: pipe-C-eDP-1
[510.890337] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.195s)
[510.903973] Starting dynamic subtest: pipe-D-eDP-1
[512.129630] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.236s)
[512.131757] Subtest compare-crc-sanitycheck-xr24: SUCCESS (4.348s)
[512.961542] [098/133] (512s left) gem_render_copy (yf-tiled-ccs-to-linear)
[513.025249] Starting subtest: yf-tiled-ccs-to-linear
[513.029894] Subtest yf-tiled-ccs-to-linear: SKIP (0.000s)
[513.213078] [099/133] (512s left) kms_pm_backlight (fade-with-dpms)
[513.297852] Starting subtest: fade-with-dpms
[515.444483] Starting dynamic subtest: eDP-1
[517.927696] Dynamic subtest eDP-1: SUCCESS (2.485s)
[517.930118] Subtest fade-with-dpms: SUCCESS (4.633s)
[518.753038] [100/133] (507s left) i915_pm_rpm (gem-pread)
[521.000312] Starting subtest: gem-pread
[523.057377] Subtest gem-pread: SUCCESS (2.061s)
[523.146017] [101/133] (502s left) i915_drm_fdinfo (virtual-busy)
[523.195730] Starting subtest: virtual-busy
[523.199856] Subtest virtual-busy: SKIP (0.000s)
[523.270018] [102/133] (502s left) kms_psr2_sf (pr-cursor-plane-update-sf)
[523.377458] Starting subtest: pr-cursor-plane-update-sf
[523.381843] Subtest pr-cursor-plane-update-sf: SKIP (0.000s)
[523.457235] [103/133] (502s left) kms_lease (cursor-implicit-plane)
[523.544564] Starting subtest: cursor-implicit-plane
[523.546862] Starting dynamic subtest: pipe-A-eDP-1
[524.000954] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.455s)
[524.013398] Starting dynamic subtest: pipe-B-eDP-1
[525.127314] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.124s)
[525.137850] Starting dynamic subtest: pipe-C-eDP-1
[526.240458] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.113s)
[526.250795] Starting dynamic subtest: pipe-D-eDP-1
[527.364246] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.123s)
[527.366599] Subtest cursor-implicit-plane: SUCCESS (3.822s)
[528.223330] [104/133] (497s left) syncobj_timeline (wait-any-complex)
[528.283303] Starting subtest: wait-any-complex
[528.346339] Subtest wait-any-complex: SUCCESS (0.062s)
[528.426522] [105/133] (497s left) gem_ctx_persistence (legacy-engines-mixed-process)
[528.489874] Starting subtest: legacy-engines-mixed-process
[528.492213] Starting dynamic subtest: render
[529.734149] Dynamic subtest render: SUCCESS (1.244s)
[529.745955] Starting dynamic subtest: bsd1
[531.730326] Dynamic subtest bsd1: SUCCESS (1.995s)
[531.741477] Starting dynamic subtest: bsd2
[533.779719] Dynamic subtest bsd2: SUCCESS (2.047s)
[533.792010] Starting dynamic subtest: blt
[535.767450] Dynamic subtest blt: SUCCESS (1.986s)
[535.779453] Starting dynamic subtest: vebox
[537.765604] Dynamic subtest vebox: SUCCESS (1.996s)
[537.768217] Subtest legacy-engines-mixed-process: SUCCESS (9.277s)
[537.845963] [106/133] (488s left) kms_flip (plain-flip-ts-check)
[537.954949] Starting subtest: plain-flip-ts-check
[537.957209] Starting dynamic subtest: A-eDP1
[546.817011] Dynamic subtest A-eDP1: SUCCESS (8.863s)
[546.833330] Starting dynamic subtest: B-eDP1
[555.644187] Dynamic subtest B-eDP1: SUCCESS (8.826s)
[555.660943] Starting dynamic subtest: C-eDP1
[564.474988] Dynamic subtest C-eDP1: SUCCESS (8.829s)
[564.488521] Starting dynamic subtest: D-eDP1
[573.305442] Dynamic subtest D-eDP1: SUCCESS (8.827s)
[573.311371] Subtest plain-flip-ts-check: SUCCESS (35.357s)
[574.496245] [107/133] (451s left) kms_invalid_mode (bad-vsync-start)
[574.610910] Starting subtest: bad-vsync-start
[574.613267] Starting dynamic subtest: pipe-A-eDP-1
[574.900024] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.288s)
[574.910433] Starting dynamic subtest: pipe-B-eDP-1
[574.912827] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[574.922691] Starting dynamic subtest: pipe-C-eDP-1
[574.924801] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[574.937563] Starting dynamic subtest: pipe-D-eDP-1
[574.939883] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[574.942211] Subtest bad-vsync-start: SUCCESS (0.294s)
[575.726466] [108/133] (450s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[575.849058] Starting subtest: basic-flip-before-cursor-varying-size
[576.773574] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.925s)
[577.659096] [109/133] (448s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions-varying-size)
[577.789218] Starting subtest: flip-vs-cursor-atomic-transitions-varying-size
[586.985264] Subtest flip-vs-cursor-atomic-transitions-varying-size: SUCCESS (9.195s)
[587.789092] [110/133] (438s left) gem_softpin (overlap)
[587.847992] Starting subtest: overlap
[588.776749] Subtest overlap: SUCCESS (0.927s)
[588.898989] [111/133] (437s left) kms_psr (psr2-sprite-mmap-gtt)
[588.991807] Starting subtest: psr2-sprite-mmap-gtt
[588.994154] Starting dynamic subtest: eDP-1
[590.401275] Dynamic subtest eDP-1: SKIP (1.406s)
[590.406282] Subtest psr2-sprite-mmap-gtt: SKIP (1.408s)
[590.590736] [112/133] (435s left) kms_frontbuffer_tracking (psr-suspend)
[590.702451] Starting subtest: psr-suspend
[628.950673] Subtest psr-suspend: SUCCESS (7.965s)
[629.078052] [113/133] (397s left) kms_psr2_sf (psr2-cursor-plane-update-sf)
[629.186876] Starting subtest: psr2-cursor-plane-update-sf
[629.189199] Starting dynamic subtest: pipe-A-eDP-1
[629.760840] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.571s)
[629.772417] Starting dynamic subtest: pipe-B-eDP-1
[631.068369] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.289s)
[631.070779] Subtest psr2-cursor-plane-update-sf: SUCCESS (1.863s)
[631.957787] [114/133] (394s left) kms_big_fb (y-tiled-16bpp-rotate-270)
[632.076065] Starting subtest: y-tiled-16bpp-rotate-270
[632.080666] Subtest y-tiled-16bpp-rotate-270: SKIP (0.000s)
[632.164926] [115/133] (394s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted)
[632.229203] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted
[632.334458] Subtest etime-multi-wait-all-for-submit-available-unsubmitted: SUCCESS (0.104s)
[632.427251] [116/133] (394s left) kms_flip (modeset-vs-vblank-race)
[632.532050] Starting subtest: modeset-vs-vblank-race
[632.534086] Starting dynamic subtest: A-eDP1
[637.215634] Dynamic subtest A-eDP1: SUCCESS (4.651s)
[637.228642] Starting dynamic subtest: B-eDP1
[641.975206] Dynamic subtest B-eDP1: SUCCESS (4.737s)
[641.989951] Starting dynamic subtest: C-eDP1
[646.610141] Dynamic subtest C-eDP1: SUCCESS (4.621s)
[646.622930] Starting dynamic subtest: D-eDP1
[651.253247] Dynamic subtest D-eDP1: SUCCESS (4.634s)
[651.258643] Subtest modeset-vs-vblank-race: SUCCESS (18.656s)
[652.428731] [117/133] (374s left) gem_exec_schedule (smoketest-all)
[652.506201] Starting subtest: smoketest-all
[684.878417] Subtest smoketest-all: SUCCESS (32.326s)
[686.140356] [118/133] (340s left) gem_mmap_gtt (basic-small-copy-odd)
[686.203867] Subtest basic-small-copy-odd: SKIP (0.000s)
[686.262432] [119/133] (340s left) gen9_exec_parse (bb-start-cmd)
[686.325595] Subtest bb-start-cmd: SKIP (0.000s)
[686.399513] [120/133] (340s left) gem_mmap_gtt (basic)
[686.458699] Subtest basic: SKIP (0.000s)
[686.520544] [121/133] (340s left) syncobj_timeline (etime-single-wait-available-unsubmitted)
[686.571413] Starting subtest: etime-single-wait-available-unsubmitted
[686.678675] Subtest etime-single-wait-available-unsubmitted: SUCCESS (0.106s)
[686.759004] [122/133] (340s left) gem_exec_alignment (forked)
[686.825631] Starting subtest: forked
[725.026428] Subtest forked: SUCCESS (38.188s)
[725.311661] [123/133] (301s left) kms_getfb (getfb2-handle-zero)
[725.454544] Starting subtest: getfb2-handle-zero
[725.456886] Subtest getfb2-handle-zero: SUCCESS (0.000s)
[725.551755] [124/133] (301s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-75)
[725.649362] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-75
[725.651634] Starting dynamic subtest: pipe-A
[725.672529] Dynamic subtest pipe-A: SKIP (0.020s)
[725.684191] Starting dynamic subtest: pipe-B
[725.706836] Dynamic subtest pipe-B: SKIP (0.032s)
[725.717127] Starting dynamic subtest: pipe-C
[725.740461] Dynamic subtest pipe-C: SKIP (0.033s)
[725.750458] Starting dynamic subtest: pipe-D
[725.773732] Dynamic subtest pipe-D: SKIP (0.033s)
[725.777989] Subtest planes-upscale-factor-0-25-downscale-factor-0-75: SKIP (0.121s)
[725.883733] [125/133] (301s left) kms_content_protection (uevent)
[726.029925] Starting subtest: uevent
[726.034493] Subtest uevent: SKIP (0.000s)
[726.154920] [126/133] (300s left) kms_plane_multiple (2x-tiling-x)
[726.253147] Starting subtest: 2x-tiling-x
[726.256797] Subtest 2x-tiling-x: SKIP (0.000s)
[726.326709] [127/133] (300s left) gem_exec_schedule (preempt-contexts)
[726.404548] Starting subtest: preempt-contexts
[726.406686] Starting dynamic subtest: rcs0
[726.457914] Dynamic subtest rcs0: SUCCESS (0.055s)
[726.468466] Starting dynamic subtest: bcs0
[726.515297] Dynamic subtest bcs0: SUCCESS (0.057s)
[726.525838] Starting dynamic subtest: vcs0
[726.563481] Dynamic subtest vcs0: SUCCESS (0.047s)
[726.575915] Starting dynamic subtest: vcs1
[726.615131] Dynamic subtest vcs1: SUCCESS (0.051s)
[726.626255] Starting dynamic subtest: vecs0
[726.664789] Dynamic subtest vecs0: SUCCESS (0.048s)
[726.674764] Starting dynamic subtest: ccs0
[726.713435] Dynamic subtest ccs0: SUCCESS (0.048s)
[726.715230] Subtest preempt-contexts: SUCCESS (0.312s)
[728.099366] [128/133] (299s left) perf_pmu (most-busy-check-all)
[728.156999] Starting subtest: most-busy-check-all
[728.159211] Starting dynamic subtest: rcs0
[730.051140] Dynamic subtest rcs0: SUCCESS (1.895s)
[730.064965] Starting dynamic subtest: bcs0
[732.035921] Dynamic subtest bcs0: SUCCESS (1.982s)
[732.051997] Starting dynamic subtest: vcs0
[734.019120] Dynamic subtest vcs0: SUCCESS (1.979s)
[734.032667] Starting dynamic subtest: vcs1
[736.003165] Dynamic subtest vcs1: SUCCESS (1.981s)
[736.016497] Starting dynamic subtest: vecs0
[738.050637] Dynamic subtest vecs0: SUCCESS (2.044s)
[738.064094] Starting dynamic subtest: ccs0
[740.034211] Dynamic subtest ccs0: SUCCESS (1.981s)
[740.037290] Subtest most-busy-check-all: SUCCESS (11.883s)
[740.143329] [129/133] (287s left) kms_rotation_crc (multiplane-rotation-cropping-top)
[740.243298] Starting subtest: multiplane-rotation-cropping-top
[757.646184] Subtest multiplane-rotation-cropping-top: SUCCESS (17.404s)
[757.758532] [130/133] (269s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[757.857696] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[757.861616] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[757.965451] [131/133] (269s left) syncobj_wait (signal)
[758.022372] Starting subtest: signal
[758.024350] Subtest signal: SUCCESS (0.000s)
[758.121347] [132/133] (269s left) kms_plane (pixel-format-y-tiled-ccs-modifier-source-clamping)
[758.209721] Starting subtest: pixel-format-y-tiled-ccs-modifier-source-clamping
[758.213183] Subtest pixel-format-y-tiled-ccs-modifier-source-clamping: SKIP (0.000s)
[758.280450] [133/133] (269s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-render)
[758.379413] Starting subtest: psr-1p-primscrn-cur-indfb-draw-render
[765.199640] Subtest psr-1p-primscrn-cur-indfb-draw-render: SUCCESS (6.821s)
[765.311820] Closing watchdogs
results: parsing output: 0/ for test: sw_sync
results: parsing output: 1/ for test: gem_busy
results: parsing output: 2/ for test: i915_query
results: parsing output: 3/ for test: gem_userptr_blits
results: parsing output: 4/ for test: perf
results: parsing output: 5/ for test: kms_flip
results: parsing output: 6/ for test: kms_feature_discovery
results: parsing output: 7/ for test: kms_atomic_transition
results: parsing output: 8/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 9/ for test: kms_plane
results: parsing output: 10/ for test: gem_mmap_offset
results: parsing output: 11/ for test: kms_ccs
results: parsing output: 12/ for test: syncobj_timeline
results: parsing output: 13/ for test: kms_psr2_sf
results: parsing output: 14/ for test: gem_mmap_wc
results: parsing output: 15/ for test: kms_big_fb
results: parsing output: 16/ for test: kms_psr
results: parsing output: 17/ for test: gem_eio
results: parsing output: 18/ for test: kms_cursor_crc
results: parsing output: 19/ for test: kms_busy
results: parsing output: 20/ for test: kms_ccs
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: kms_big_fb
results: parsing output: 23/ for test: gem_close_race
results: parsing output: 24/ for test: api_intel_allocator
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_ccs
results: parsing output: 27/ for test: gem_mmap_wc
results: parsing output: 28/ for test: kms_cursor_legacy
results: parsing output: 29/ for test: kms_big_fb
results: parsing output: 30/ for test: kms_cursor_crc
results: parsing output: 31/ for test: gem_exec_fence
results: parsing output: 32/ for test: kms_flip
results: parsing output: 33/ for test: kms_addfb_basic
results: parsing output: 34/ for test: gem_lmem_swapping
results: parsing output: 35/ for test: api_intel_bb
results: parsing output: 36/ for test: kms_vblank
results: parsing output: 37/ for test: kms_fbcon_fbt
results: parsing output: 38/ for test: kms_cursor_crc
results: parsing output: 39/ for test: kms_ccs
results: parsing output: 40/ for test: kms_atomic_interruptible
results: parsing output: 41/ for test: gem_lmem_swapping
results: parsing output: 42/ for test: gem_workarounds
results: parsing output: 43/ for test: kms_chamelium_edid
results: parsing output: 44/ for test: syncobj_timeline
results: parsing output: 45/ for test: gem_exec_reloc
results: parsing output: 46/ for test: gem_userptr_blits
results: parsing output: 47/ for test: kms_universal_plane
results: parsing output: 48/ for test: kms_big_fb
results: parsing output: 49/ for test: gem_ctx_persistence
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: kms_color
results: parsing output: 52/ for test: gem_exec_capture
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: gem_render_copy
results: parsing output: 55/ for test: perf
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: gem_exec_whisper
results: parsing output: 58/ for test: prime_busy
results: parsing output: 59/ for test: gem_exec_balancer
results: parsing output: 60/ for test: gem_mmap_wc
results: parsing output: 61/ for test: sysfs_preempt_timeout
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: gem_spin_batch
results: parsing output: 64/ for test: dmabuf_sync_file
results: parsing output: 65/ for test: syncobj_wait
results: parsing output: 66/ for test: gem_pread
results: parsing output: 67/ for test: kms_chamelium_hpd
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: kms_vrr
results: parsing output: 71/ for test: kms_setmode
results: parsing output: 72/ for test: i915_hangman
results: parsing output: 73/ for test: kms_flip_scaled_crc
results: parsing output: 74/ for test: gem_exec_reloc
results: parsing output: 75/ for test: i915_query
results: parsing output: 76/ for test: kms_setmode
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: kms_atomic
results: parsing output: 79/ for test: kms_flip_scaled_crc
results: parsing output: 80/ for test: prime_mmap
results: parsing output: 81/ for test: gem_userptr_blits
results: parsing output: 82/ for test: kms_psr
results: parsing output: 83/ for test: kms_ccs
results: parsing output: 84/ for test: gem_exec_schedule
results: parsing output: 85/ for test: gem_exec_fence
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: gem_wait
results: parsing output: 88/ for test: kms_getfb
results: parsing output: 89/ for test: kms_psr
results: parsing output: 90/ for test: syncobj_wait
results: parsing output: 91/ for test: gem_basic
results: parsing output: 92/ for test: kms_pm_rpm
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: kms_big_fb
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: kms_pipe_crc_basic
results: parsing output: 97/ for test: gem_render_copy
results: parsing output: 98/ for test: kms_pm_backlight
results: parsing output: 99/ for test: i915_pm_rpm
results: parsing output: 100/ for test: i915_drm_fdinfo
results: parsing output: 101/ for test: kms_psr2_sf
results: parsing output: 102/ for test: kms_lease
results: parsing output: 103/ for test: syncobj_timeline
results: parsing output: 104/ for test: gem_ctx_persistence
results: parsing output: 105/ for test: kms_flip
results: parsing output: 106/ for test: kms_invalid_mode
results: parsing output: 107/ for test: kms_cursor_legacy
results: parsing output: 108/ for test: kms_cursor_legacy
results: parsing output: 109/ for test: gem_softpin
results: parsing output: 110/ for test: kms_psr
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: kms_psr2_sf
results: parsing output: 113/ for test: kms_big_fb
results: parsing output: 114/ for test: syncobj_timeline
results: parsing output: 115/ for test: kms_flip
results: parsing output: 116/ for test: gem_exec_schedule
results: parsing output: 117/ for test: gem_mmap_gtt
results: parsing output: 118/ for test: gen9_exec_parse
results: parsing output: 119/ for test: gem_mmap_gtt
results: parsing output: 120/ for test: syncobj_timeline
results: parsing output: 121/ for test: gem_exec_alignment
results: parsing output: 122/ for test: kms_getfb
results: parsing output: 123/ for test: kms_plane_scaling
results: parsing output: 124/ for test: kms_content_protection
results: parsing output: 125/ for test: kms_plane_multiple
results: parsing output: 126/ for test: gem_exec_schedule
results: parsing output: 127/ for test: perf_pmu
results: parsing output: 128/ for test: kms_rotation_crc
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: syncobj_wait
results: parsing output: 131/ for test: kms_plane
results: parsing output: 132/ for test: kms_frontbuffer_tracking
Done.