[59.503582] Initializing watchdogs
[59.503630]   /dev/watchdog0
[59.519632] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[59.524329] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.527086] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.530926] [001/156] (960s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc)
[59.656082] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc
[66.968110] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (7.360s)
[67.077312] [002/156] (952s left) kms_chamelium_hpd (vga-hpd-enable-disable-mode)
[67.456095] Subtest vga-hpd-enable-disable-mode: SKIP (0.000s)
[68.299423] [003/156] (951s left) kms_async_flips (async-flip-with-page-flip-events-tiled-atomic)
[68.402383] Starting subtest: async-flip-with-page-flip-events-tiled-atomic
[68.784354] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs
[71.638885] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: SUCCESS (2.806s)
[71.648656] Starting dynamic subtest: pipe-A-eDP-1-4
[73.830306] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.195s)
[73.841193] Starting dynamic subtest: pipe-A-eDP-1-4
[75.785280] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.008s)
[75.795655] Starting dynamic subtest: pipe-A-eDP-1-x
[78.024360] Dynamic subtest pipe-A-eDP-1-x: SUCCESS (2.189s)
[78.342314] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs
[81.149942] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: SUCCESS (2.764s)
[81.160845] Starting dynamic subtest: pipe-B-eDP-1-4
[83.333897] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.186s)
[83.347361] Starting dynamic subtest: pipe-B-eDP-1-4
[85.291608] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.008s)
[85.304675] Starting dynamic subtest: pipe-B-eDP-1-x
[87.544696] Dynamic subtest pipe-B-eDP-1-x: SUCCESS (2.200s)
[87.870529] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs
[90.632279] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.768s)
[90.643524] Starting dynamic subtest: pipe-C-eDP-1-4
[92.857561] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.173s)
[92.868870] Starting dynamic subtest: pipe-C-eDP-1-4
[94.816210] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.010s)
[94.826807] Starting dynamic subtest: pipe-C-eDP-1-x
[97.058244] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.189s)
[97.370704] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs
[100.160692] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.796s)
[100.173852] Starting dynamic subtest: pipe-D-eDP-1-4
[102.398803] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.185s)
[102.409285] Starting dynamic subtest: pipe-D-eDP-1-4
[104.356102] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.010s)
[104.364548] Starting dynamic subtest: pipe-D-eDP-1-x
[106.586386] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.178s)
[106.588654] Subtest async-flip-with-page-flip-events-tiled-atomic: SUCCESS (38.148s)
[107.768030] [004/156] (912s left) gem_exec_fence (syncobj-export)
[108.229075] Starting subtest: syncobj-export
[108.236815] Subtest syncobj-export: SUCCESS (0.010s)
[109.393130] [FACT gem_exec_fence (syncobj-export)] new: kernel.kmod_is_loaded.vgem: true
[109.396491] [005/156] (910s left) kms_big_fb (y-tiled-8bpp-rotate-90)
[109.501098] Starting subtest: y-tiled-8bpp-rotate-90
[109.505098] Subtest y-tiled-8bpp-rotate-90: SKIP (0.000s)
[109.607673] [006/156] (910s left) kms_rotation_crc (multiplane-rotation)
[109.713095] Starting subtest: multiplane-rotation
[127.228421] Subtest multiplane-rotation: SUCCESS (17.517s)
[127.340290] [007/156] (892s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[127.407672] Starting subtest: framebuffer-vs-set-tiling
[127.411528] Subtest framebuffer-vs-set-tiling: SKIP (0.000s)
[127.545133] [008/156] (892s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-move)
[127.645642] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-move
[127.649296] Subtest fbcpsr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[127.760540] [009/156] (892s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-shrfb-pgflip-blt)
[127.855086] Starting subtest: fbchdr-2p-scndscrn-shrfb-pgflip-blt
[127.858549] Subtest fbchdr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[127.944178] [010/156] (892s left) kms_pm_rpm (pm-tiling)
[129.489152] Starting subtest: pm-tiling
[129.493994] Subtest pm-tiling: SKIP (0.000s)
[130.300418] [011/156] (890s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-upscaling)
[130.389030] Starting subtest: flip-32bpp-linear-to-64bpp-linear-upscaling
[130.391321] Starting dynamic subtest: pipe-A-default-mode
[133.110417] Dynamic subtest pipe-A-default-mode: SUCCESS (2.721s)
[133.112764] Subtest flip-32bpp-linear-to-64bpp-linear-upscaling: SUCCESS (2.723s)
[133.979118] [012/156] (886s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs)
[134.073432] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs
[134.075896] Starting dynamic subtest: pipe-A-eDP-1
[134.930991] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.849s)
[134.940501] Starting dynamic subtest: pipe-B-eDP-1
[136.656548] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.720s)
[136.672922] Starting dynamic subtest: pipe-C-eDP-1
[138.194049] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.539s)
[138.203122] Starting dynamic subtest: pipe-D-eDP-1
[139.780582] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.585s)
[139.782826] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs: SUCCESS (5.697s)
[141.004281] [013/156] (879s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-blt)
[141.109240] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-blt
[141.113501] Subtest psr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[141.209445] [014/156] (879s left) syncobj_timeline (wait-for-submit-delayed-submit)
[141.269325] Starting subtest: wait-for-submit-delayed-submit
[141.372037] Subtest wait-for-submit-delayed-submit: SUCCESS (0.103s)
[141.463138] [015/156] (879s left) i915_drm_fdinfo (memory-info-shared)
[141.502300] Starting subtest: memory-info-shared
[141.504592] Starting dynamic subtest: smem0
[141.553709] Dynamic subtest smem0: SUCCESS (0.053s)
[141.555884] Subtest memory-info-shared: SUCCESS (0.054s)
[141.627473] [016/156] (879s left) syncobj_wait (multi-wait-submitted)
[141.685293] Starting subtest: multi-wait-submitted
[141.792587] Subtest multi-wait-submitted: SUCCESS (0.107s)
[141.873031] [017/156] (878s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-shrfb-msflip-blt)
[141.970673] Starting subtest: psrhdr-1p-primscrn-shrfb-msflip-blt
[141.974967] Subtest psrhdr-1p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[142.078344] [018/156] (878s left) kms_flip (2x-nonexisting-fb)
[142.178064] Starting subtest: 2x-nonexisting-fb
[142.186423] Subtest 2x-nonexisting-fb: SKIP (0.011s)
[142.264954] [019/156] (878s left) kms_psr (fbc-pr-sprite-mmap-gtt)
[142.366813] Starting subtest: fbc-pr-sprite-mmap-gtt
[142.370746] Subtest fbc-pr-sprite-mmap-gtt: SKIP (0.000s)
[142.438971] [020/156] (878s left) kms_flip (flip-vs-expired-vblank)
[142.521486] Starting subtest: flip-vs-expired-vblank
[142.523959] Starting dynamic subtest: A-eDP1
[151.230103] Dynamic subtest A-eDP1: SUCCESS (8.674s)
[151.245266] Starting dynamic subtest: B-eDP1
[159.965847] Dynamic subtest B-eDP1: SUCCESS (8.730s)
[159.976928] Starting dynamic subtest: C-eDP1
[168.591575] Dynamic subtest C-eDP1: SUCCESS (8.613s)
[168.603196] Starting dynamic subtest: D-eDP1
[177.230893] Dynamic subtest D-eDP1: SUCCESS (8.637s)
[177.233568] Subtest flip-vs-expired-vblank: SUCCESS (34.665s)
[178.350447] [021/156] (842s left) syncobj_wait (wait-all-interrupted)
[178.396240] Starting subtest: wait-all-interrupted
[178.496954] Subtest wait-all-interrupted: SUCCESS (0.101s)
[178.589481] [022/156] (842s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-render)
[178.694461] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-render
[178.698855] Subtest fbchdr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[178.783956] [023/156] (842s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-blt)
[178.888919] Starting subtest: psr-1p-offscreen-pri-indfb-draw-blt
[185.826073] Subtest psr-1p-offscreen-pri-indfb-draw-blt: SUCCESS (6.941s)
[185.950143] [024/156] (835s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[186.055202] Starting subtest: psrhdr-1p-offscreen-pri-shrfb-draw-mmap-wc
[186.059556] Subtest psrhdr-1p-offscreen-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[186.155449] [025/156] (834s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-shrfb-draw-blt)
[186.254117] Starting subtest: psrhdr-2p-primscrn-pri-shrfb-draw-blt
[186.258368] Subtest psrhdr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[186.346019] [026/156] (834s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-cpu)
[186.444102] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-cpu
[190.826936] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (4.389s)
[192.003805] [027/156] (829s left) gem_exec_params (rel-constants-invalid-ring)
[192.056213] Starting subtest: rel-constants-invalid-ring
[192.058643] Subtest rel-constants-invalid-ring: SUCCESS (0.000s)
[192.155414] [028/156] (828s left) gem_exec_reloc (basic-wc-gtt-active)
[192.214852] Subtest basic-wc-gtt-active: SKIP (0.000s)
[192.285478] [029/156] (828s left) kms_busy (extended-modeset-hang-oldfb-with-reset)
[192.390465] Starting subtest: extended-modeset-hang-oldfb-with-reset
[192.392777] Starting dynamic subtest: pipe-A
[204.054092] Dynamic subtest pipe-A: SUCCESS (11.662s)
[204.068560] Starting dynamic subtest: pipe-D
[216.340252] Dynamic subtest pipe-D: SUCCESS (12.281s)
[216.342682] Subtest extended-modeset-hang-oldfb-with-reset: SUCCESS (23.948s)
[217.146223] [030/156] (804s left) kms_big_fb (4-tiled-addfb)
[217.247202] Starting subtest: 4-tiled-addfb
[217.249505] Subtest 4-tiled-addfb: SUCCESS (0.000s)
[217.349987] [031/156] (803s left) syncobj_timeline (invalid-transfer-bad-pad)
[217.394678] Starting subtest: invalid-transfer-bad-pad
[217.396872] Subtest invalid-transfer-bad-pad: SUCCESS (0.000s)
[217.513644] [032/156] (803s left) api_intel_bb (bb-with-allocator)
[217.564982] Starting subtest: bb-with-allocator
[217.570318] Subtest bb-with-allocator: SUCCESS (0.007s)
[217.660781] [033/156] (803s left) kms_psr (pr-cursor-plane-move)
[217.750484] Starting subtest: pr-cursor-plane-move
[217.754845] Subtest pr-cursor-plane-move: SKIP (0.000s)
[217.835712] [034/156] (803s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-blt)
[217.939848] Starting subtest: fbc-rgb101010-draw-blt
[220.458289] Subtest fbc-rgb101010-draw-blt: SUCCESS (2.522s)
[221.665338] [035/156] (799s left) gem_pxp (create-regular-buffer)
[221.791128] Starting subtest: create-regular-buffer
[221.793271] Subtest create-regular-buffer: SUCCESS (0.000s)
[221.928016] [036/156] (799s left) kms_pipe_crc_basic (hang-read-crc)
[222.024194] Starting subtest: hang-read-crc
[222.026587] Starting dynamic subtest: pipe-A-eDP-1
[224.113283] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.089s)
[224.122987] Starting dynamic subtest: pipe-B-eDP-1
[226.994087] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.878s)
[227.006441] Starting dynamic subtest: pipe-C-eDP-1
[229.711512] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.717s)
[229.721557] Starting dynamic subtest: pipe-D-eDP-1
[232.379739] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.667s)
[232.381848] Subtest hang-read-crc: SUCCESS (10.356s)
[233.204648] [037/156] (788s left) kms_chamelium_frames (hdmi-cmp-planar-formats)
[233.596671] Subtest hdmi-cmp-planar-formats: SKIP (0.000s)
[234.409240] [038/156] (787s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-upscaling)
[234.511068] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-upscaling
[234.513252] Starting dynamic subtest: pipe-A-default-mode
[237.286075] Dynamic subtest pipe-A-default-mode: SUCCESS (2.774s)
[237.288480] Subtest flip-64bpp-xtile-to-16bpp-xtile-upscaling: SUCCESS (2.776s)
[238.117845] [039/156] (783s left) kms_psr (fbc-psr-primary-page-flip)
[238.206206] Starting subtest: fbc-psr-primary-page-flip
[238.208493] Starting dynamic subtest: eDP-1
[238.212524] Dynamic subtest eDP-1: SKIP (0.000s)
[238.216403] Subtest fbc-psr-primary-page-flip: SKIP (0.001s)
[238.280412] [040/156] (783s left) gem_eio (in-flight-internal-1us)
[238.483113] Starting subtest: in-flight-internal-1us
[240.397872] Subtest in-flight-internal-1us: SUCCESS (1.916s)
[240.548220] [041/156] (781s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[240.656913] Starting subtest: fbcpsrhdr-1p-offscreen-pri-shrfb-draw-mmap-wc
[240.661040] Subtest fbcpsrhdr-1p-offscreen-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[240.754134] [042/156] (780s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[240.842989] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt
[240.847149] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[240.965072] [043/156] (780s left) perf_pmu (semaphore-wait)
[241.005259] Starting subtest: semaphore-wait
[241.007649] Starting dynamic subtest: rcs0
[241.517595] Dynamic subtest rcs0: SUCCESS (0.513s)
[241.533865] Starting dynamic subtest: bcs0
[242.032418] Dynamic subtest bcs0: SUCCESS (0.511s)
[242.045940] Starting dynamic subtest: vcs0
[242.547535] Dynamic subtest vcs0: SUCCESS (0.512s)
[242.560064] Starting dynamic subtest: vcs1
[243.063089] Dynamic subtest vcs1: SUCCESS (0.512s)
[243.076092] Starting dynamic subtest: vecs0
[243.577703] Dynamic subtest vecs0: SUCCESS (0.511s)
[243.589773] Starting dynamic subtest: ccs0
[244.095100] Dynamic subtest ccs0: SUCCESS (0.514s)
[244.097997] Subtest semaphore-wait: SUCCESS (3.093s)
[244.365541] [044/156] (777s left) drm_read (invalid-buffer)
[244.523570] Starting subtest: invalid-buffer
[244.526144] Subtest invalid-buffer: SUCCESS (0.000s)
[244.650577] [045/156] (777s left) kms_big_fb (4-tiled-16bpp-rotate-0)
[244.750453] Starting subtest: 4-tiled-16bpp-rotate-0
[246.085729] Subtest 4-tiled-16bpp-rotate-0: SUCCESS (1.335s)
[246.225454] [046/156] (775s left) api_intel_bb (render-ccs)
[246.284072] Starting subtest: render-ccs
[246.641723] Subtest render-ccs: SUCCESS (0.357s)
[246.735860] [047/156] (775s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-blt)
[246.849228] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-blt
[246.853398] Subtest hdr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[246.949258] [048/156] (775s left) kms_ccs (bad-pixel-format-y-tiled-gen12-mc-ccs)
[247.043794] Starting subtest: bad-pixel-format-y-tiled-gen12-mc-ccs
[247.046172] Starting dynamic subtest: pipe-A-eDP-1
[247.068998] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[247.081288] Starting dynamic subtest: pipe-B-eDP-1
[247.102547] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[247.111452] Starting dynamic subtest: pipe-C-eDP-1
[247.133301] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[247.144798] Starting dynamic subtest: pipe-D-eDP-1
[247.167280] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[247.171702] Subtest bad-pixel-format-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[249.297687] [049/156] (772s left) gem_softpin (evict-snoop)
[249.363894] Starting subtest: evict-snoop
[249.368462] Subtest evict-snoop: SKIP (0.000s)
[249.500246] [050/156] (772s left) i915_pm_rps (min-max-config-idle)
[249.558705] Starting subtest: min-max-config-idle
[249.563304] Subtest min-max-config-idle: SKIP (0.001s)
[249.660372] [051/156] (772s left) gem_lmem_swapping (random)
[249.704735] Subtest random: SKIP (0.000s)
[249.745331] [052/156] (772s left) kms_frontbuffer_tracking (psr-1p-rte)
[249.842759] Starting subtest: psr-1p-rte
[255.208461] Subtest psr-1p-rte: SUCCESS (5.372s)
[255.368965] [053/156] (766s left) gem_wait (wait)
[255.429814] Starting subtest: wait
[255.432207] Starting dynamic subtest: all-engines
[257.278218] Dynamic subtest all-engines: SUCCESS (1.846s)
[257.289312] Starting dynamic subtest: rcs0
[258.324857] Dynamic subtest rcs0: SUCCESS (1.045s)
[258.337849] Starting dynamic subtest: bcs0
[259.373090] Dynamic subtest bcs0: SUCCESS (1.046s)
[259.385322] Starting dynamic subtest: vcs0
[260.418242] Dynamic subtest vcs0: SUCCESS (1.043s)
[260.430036] Starting dynamic subtest: vcs1
[261.463604] Dynamic subtest vcs1: SUCCESS (1.043s)
[261.475629] Starting dynamic subtest: vecs0
[262.508756] Dynamic subtest vecs0: SUCCESS (1.043s)
[262.520965] Starting dynamic subtest: ccs0
[263.558396] Dynamic subtest ccs0: SUCCESS (1.047s)
[263.561125] Subtest wait: SUCCESS (8.130s)
[263.752869] [054/156] (758s left) kms_pm_rpm (cursor)
[265.490560] Starting subtest: cursor
[265.495758] Subtest cursor: SKIP (0.000s)
[266.074530] [055/156] (756s left) kms_vrr (flipline)
[266.166629] Starting subtest: flipline
[266.173256] Subtest flipline: SKIP (0.000s)
[266.251572] [056/156] (756s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-render)
[266.360267] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-render
[266.364301] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[266.477042] [057/156] (755s left) gem_ctx_bad_destroy (invalid-ctx)
[266.529114] Starting subtest: invalid-ctx
[266.531608] Subtest invalid-ctx: SUCCESS (0.000s)
[266.603662] [058/156] (755s left) kms_properties (invalid-properties-legacy)
[266.697138] Starting subtest: invalid-properties-legacy
[266.802115] Subtest invalid-properties-legacy: SUCCESS (0.105s)
[266.888788] [059/156] (755s left) kms_psr2_sf (psr2-overlay-plane-move-continuous-exceed-sf)
[266.987961] Starting subtest: psr2-overlay-plane-move-continuous-exceed-sf
[266.990311] Starting dynamic subtest: pipe-A-eDP-1
[278.302578] Dynamic subtest pipe-A-eDP-1: SUCCESS (11.318s)
[278.312745] Starting dynamic subtest: pipe-B-eDP-1
[290.379827] Dynamic subtest pipe-B-eDP-1: SUCCESS (12.075s)
[290.382137] Subtest psr2-overlay-plane-move-continuous-exceed-sf: SUCCESS (23.396s)
[291.219255] [060/156] (731s left) perf_pmu (busy-idle-no-semaphores)
[291.265059] Starting subtest: busy-idle-no-semaphores
[291.267378] Starting dynamic subtest: rcs0
[291.924955] Dynamic subtest rcs0: SUCCESS (0.659s)
[291.935034] Starting dynamic subtest: bcs0
[292.578994] Dynamic subtest bcs0: SUCCESS (0.651s)
[292.591270] Starting dynamic subtest: vcs0
[293.231165] Dynamic subtest vcs0: SUCCESS (0.649s)
[293.243885] Starting dynamic subtest: vcs1
[294.891757] Dynamic subtest vcs1: SUCCESS (1.658s)
[294.904286] Starting dynamic subtest: vecs0
[296.935349] Dynamic subtest vecs0: SUCCESS (2.040s)
[296.947994] Starting dynamic subtest: ccs0
[298.920917] Dynamic subtest ccs0: SUCCESS (1.983s)
[298.924029] Subtest busy-idle-no-semaphores: SUCCESS (7.656s)
[299.042748] [061/156] (723s left) syncobj_timeline (etime-single-wait-all-for-submit-submitted)
[299.091195] Starting subtest: etime-single-wait-all-for-submit-submitted
[299.193511] Subtest etime-single-wait-all-for-submit-submitted: SUCCESS (0.102s)
[299.275944] [062/156] (723s left) kms_frontbuffer_tracking (psrhdr-rgb565-draw-pwrite)
[299.381427] Starting subtest: psrhdr-rgb565-draw-pwrite
[299.385565] Subtest psrhdr-rgb565-draw-pwrite: SKIP (0.000s)
[299.495085] [063/156] (723s left) kms_flip (plain-flip-ts-check-interruptible)
[299.590089] Starting subtest: plain-flip-ts-check-interruptible
[299.592414] Starting dynamic subtest: A-eDP1
[308.503225] Dynamic subtest A-eDP1: SUCCESS (8.911s)
[308.513709] Starting dynamic subtest: B-eDP1
[317.363482] Dynamic subtest B-eDP1: SUCCESS (8.824s)
[317.377255] Starting dynamic subtest: C-eDP1
[326.189091] Dynamic subtest C-eDP1: SUCCESS (8.815s)
[326.202749] Starting dynamic subtest: D-eDP1
[335.027786] Dynamic subtest D-eDP1: SUCCESS (8.832s)
[335.030125] Subtest plain-flip-ts-check-interruptible: SUCCESS (35.393s)
[336.171157] [064/156] (686s left) kms_explicit_fence (multiplane-atomic-fence-wait)
[336.314932] Starting subtest: multiplane-atomic-fence-wait
[336.633483] Subtest multiplane-atomic-fence-wait: SUCCESS (0.318s)
[337.812670] [065/156] (684s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[337.923313] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-cpu
[337.927578] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[338.036637] [066/156] (684s left) syncobj_timeline (single-wait-all-available-submitted)
[338.089169] Starting subtest: single-wait-all-available-submitted
[338.091084] Subtest single-wait-all-available-submitted: SUCCESS (0.000s)
[338.208237] [067/156] (684s left) gem_request_retire (retire-vma-not-inactive)
[338.268056] Starting subtest: retire-vma-not-inactive
[338.330104] Subtest retire-vma-not-inactive: SUCCESS (0.058s)
[338.460502] [068/156] (684s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-indfb-draw-blt)
[338.560541] Starting subtest: fbchdr-1p-primscrn-pri-indfb-draw-blt
[338.564539] Subtest fbchdr-1p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[338.667233] [069/156] (684s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render)
[338.770580] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render
[338.773930] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[338.874102] [070/156] (684s left) perf_pmu (all-busy-check-all)
[338.912278] Starting subtest: all-busy-check-all
[340.286430] Subtest all-busy-check-all: SUCCESS (1.372s)
[340.384118] [071/156] (682s left) gem_lmem_swapping (heavy-verify-random)
[340.428390] Subtest heavy-verify-random: SKIP (0.000s)
[340.471587] [072/156] (682s left) gem_ringfill (basic-all)
[346.306915] Starting subtest: basic-all
[347.474585] Subtest basic-all: SUCCESS (1.167s)
[347.608867] [073/156] (675s left) gen7_exec_parse (basic-rejected)
[347.672205] Subtest basic-rejected: SKIP (0.000s)
[347.750928] [074/156] (675s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[347.868054] Starting subtest: cursorB-vs-flipA-toggle
[347.872810] Subtest cursorB-vs-flipA-toggle: SKIP (0.001s)
[348.013180] [075/156] (675s left) kms_lease (cursor-implicit-plane)
[348.108767] Starting subtest: cursor-implicit-plane
[348.111137] Starting dynamic subtest: pipe-A-eDP-1
[348.571073] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.462s)
[348.581153] Starting dynamic subtest: pipe-B-eDP-1
[349.723886] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.150s)
[349.734627] Starting dynamic subtest: pipe-C-eDP-1
[350.819509] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.095s)
[350.829969] Starting dynamic subtest: pipe-D-eDP-1
[351.943429] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.122s)
[351.945922] Subtest cursor-implicit-plane: SUCCESS (3.836s)
[352.806078] [076/156] (670s left) kms_rotation_crc (primary-rotation-180)
[352.901160] Starting subtest: primary-rotation-180
[359.806273] Subtest primary-rotation-180: SUCCESS (6.906s)
[360.980539] [077/156] (662s left) kms_color (ctm-0-75)
[361.080172] Starting subtest: ctm-0-75
[361.082336] Starting dynamic subtest: pipe-A-eDP-1
[366.029478] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.950s)
[366.040487] Starting dynamic subtest: pipe-B-eDP-1
[371.738534] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.708s)
[371.749640] Starting dynamic subtest: pipe-C-eDP-1
[377.137411] Dynamic subtest pipe-C-eDP-1: SUCCESS (5.396s)
[377.147173] Starting dynamic subtest: pipe-D-eDP-1
[382.472420] Dynamic subtest pipe-D-eDP-1: SUCCESS (5.335s)
[382.474888] Subtest ctm-0-75: SUCCESS (21.393s)
[383.308937] [078/156] (639s left) syncobj_wait (multi-wait-all-for-submit-signaled)
[383.376430] Starting subtest: multi-wait-all-for-submit-signaled
[383.378733] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.000s)
[383.510680] [079/156] (639s left) syncobj_wait (single-wait-all-for-submit-unsubmitted)
[383.564516] Starting subtest: single-wait-all-for-submit-unsubmitted
[383.664781] Subtest single-wait-all-for-submit-unsubmitted: SUCCESS (0.101s)
[383.746921] [080/156] (639s left) gem_ctx_param (root-set)
[383.799151] Starting subtest: root-set
[383.801508] Subtest root-set: SUCCESS (0.001s)
[383.909053] [081/156] (639s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-fullscreen)
[384.005936] Starting subtest: fbc-1p-primscrn-spr-indfb-fullscreen
[387.979330] Subtest fbc-1p-primscrn-spr-indfb-fullscreen: SUCCESS (3.978s)
[389.127232] [082/156] (634s left) kms_frontbuffer_tracking (fbchdr-rgb565-draw-mmap-gtt)
[389.233350] Starting subtest: fbchdr-rgb565-draw-mmap-gtt
[389.237877] Subtest fbchdr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[389.339826] [083/156] (634s left) gem_mmap_gtt (zero-extend)
[389.392308] Subtest zero-extend: SKIP (0.000s)
[389.462719] [084/156] (634s left) gem_exec_params (invalid-bsd1-flag-on-blt)
[389.527038] Starting subtest: invalid-bsd1-flag-on-blt
[389.528943] Subtest invalid-bsd1-flag-on-blt: SUCCESS (0.000s)
[389.629998] [085/156] (633s left) kms_frontbuffer_tracking (hdr-2p-primscrn-indfb-pgflip-blt)
[389.729068] Starting subtest: hdr-2p-primscrn-indfb-pgflip-blt
[389.733262] Subtest hdr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[389.826476] [086/156] (633s left) fbdev (pan)
[389.863596] Starting subtest: pan
[389.885481] Subtest pan: SUCCESS (0.025s)
[389.956987] [087/156] (633s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-onoff)
[390.057213] Starting subtest: fbchdr-2p-primscrn-spr-indfb-onoff
[390.060647] Subtest fbchdr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[390.168159] [088/156] (633s left) gem_exec_params (mmapped)
[390.225769] Starting subtest: mmapped
[390.231034] Subtest mmapped: SUCCESS (0.002s)
[390.371225] [089/156] (633s left) kms_frontbuffer_tracking (psrhdr-rgb101010-draw-mmap-cpu)
[390.474139] Starting subtest: psrhdr-rgb101010-draw-mmap-cpu
[390.477615] Subtest psrhdr-rgb101010-draw-mmap-cpu: SKIP (0.000s)
[390.562090] [090/156] (633s left) vgem_basic (bad-pad)
[390.605728] Starting subtest: bad-pad
[390.607468] Subtest bad-pad: SUCCESS (0.000s)
[390.662452] [091/156] (633s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[390.763907] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-wc
[397.936217] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (7.176s)
[398.051250] [092/156] (625s left) kms_plane_scaling (plane-downscale-factor-0-75-with-pixel-format)
[398.145300] Starting subtest: plane-downscale-factor-0-75-with-pixel-format
[398.147614] Starting dynamic subtest: pipe-A
[399.328577] Dynamic subtest pipe-A: SUCCESS (1.185s)
[399.338470] Starting dynamic subtest: pipe-B
[401.533908] Dynamic subtest pipe-B: SUCCESS (2.204s)
[401.543566] Starting dynamic subtest: pipe-C
[402.747139] Dynamic subtest pipe-C: SUCCESS (1.212s)
[402.759651] Starting dynamic subtest: pipe-D
[403.979998] Dynamic subtest pipe-D: SUCCESS (1.232s)
[403.982350] Subtest plane-downscale-factor-0-75-with-pixel-format: SUCCESS (5.837s)
[405.179580] [093/156] (618s left) kms_frontbuffer_tracking (hdr-rgb565-draw-mmap-gtt)
[405.289101] Starting subtest: hdr-rgb565-draw-mmap-gtt
[405.293381] Subtest hdr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[405.397213] [094/156] (618s left) kms_plane_cursor (viewport)
[405.481195] Starting subtest: viewport
[405.485862] Starting dynamic subtest: pipe-A-eDP-1-size-64
[408.102749] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.621s)
[408.166147] Starting dynamic subtest: pipe-A-eDP-1-size-128
[410.737140] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.582s)
[410.796147] Starting dynamic subtest: pipe-A-eDP-1-size-256
[413.337513] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.549s)
[413.688798] Starting dynamic subtest: pipe-B-eDP-1-size-64
[416.956063] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.277s)
[417.016520] Starting dynamic subtest: pipe-B-eDP-1-size-128
[419.602115] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.593s)
[419.664496] Starting dynamic subtest: pipe-B-eDP-1-size-256
[422.225245] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.571s)
[422.586726] Starting dynamic subtest: pipe-C-eDP-1-size-64
[425.582321] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (3.006s)
[425.619765] Starting dynamic subtest: pipe-C-eDP-1-size-128
[427.815377] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.203s)
[427.855236] Starting dynamic subtest: pipe-C-eDP-1-size-256
[430.059290] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.214s)
[430.393044] Starting dynamic subtest: pipe-D-eDP-1-size-64
[433.256158] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.876s)
[433.294109] Starting dynamic subtest: pipe-D-eDP-1-size-128
[435.478410] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.192s)
[435.518495] Starting dynamic subtest: pipe-D-eDP-1-size-256
[437.711431] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.202s)
[438.028919] Subtest viewport: SUCCESS (32.548s)
[438.909231] [095/156] (585s left) kms_rotation_crc (primary-y-tiled-reflect-x-90)
[439.001836] Starting subtest: primary-y-tiled-reflect-x-90
[439.006838] Subtest primary-y-tiled-reflect-x-90: SKIP (0.001s)
[439.078512] [096/156] (585s left) kms_cursor_edge_walk (128x128-right-edge)
[439.177619] Starting subtest: 128x128-right-edge
[439.179980] Starting dynamic subtest: pipe-A-eDP-1
[442.670537] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.494s)
[442.681629] Starting dynamic subtest: pipe-D-eDP-1
[445.869286] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.196s)
[445.871666] Subtest 128x128-right-edge: SUCCESS (6.693s)
[446.688457] [097/156] (577s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc)
[446.790737] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc
[446.793090] Starting dynamic subtest: pipe-A-eDP-1
[446.818079] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[446.829394] Starting dynamic subtest: pipe-B-eDP-1
[446.853218] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[446.862117] Starting dynamic subtest: pipe-C-eDP-1
[446.885912] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[446.895072] Starting dynamic subtest: pipe-D-eDP-1
[446.919235] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[446.923622] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.005s)
[449.036781] [098/156] (575s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-cur-indfb-draw-pwrite)
[449.142660] Starting subtest: fbcpsrhdr-1p-primscrn-cur-indfb-draw-pwrite
[449.147116] Subtest fbcpsrhdr-1p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[449.241453] [099/156] (574s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-onoff)
[449.343872] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-onoff
[449.347775] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[449.455522] [100/156] (574s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-blt)
[449.563326] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-blt
[449.567576] Subtest psr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[449.678473] [101/156] (574s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-cur-indfb-draw-mmap-wc)
[449.774756] Starting subtest: fbchdr-2p-primscrn-cur-indfb-draw-mmap-wc
[449.779101] Subtest fbchdr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[449.883867] [102/156] (574s left) gem_exec_schedule (implicit-write-read)
[449.956456] Starting subtest: implicit-write-read
[449.958433] Starting dynamic subtest: rcs0
[450.057388] Dynamic subtest rcs0: SUCCESS (0.112s)
[450.074576] Starting dynamic subtest: vcs0
[450.137992] Dynamic subtest vcs0: SUCCESS (0.079s)
[450.150184] Starting dynamic subtest: vcs1
[450.222267] Dynamic subtest vcs1: SUCCESS (0.083s)
[450.234459] Starting dynamic subtest: vecs0
[450.301710] Dynamic subtest vecs0: SUCCESS (0.079s)
[450.314330] Starting dynamic subtest: ccs0
[450.393284] Dynamic subtest ccs0: SUCCESS (0.091s)
[450.395498] Subtest implicit-write-read: SUCCESS (0.448s)
[451.421082] [103/156] (572s left) gem_workarounds (suspend-resume-fd)
[451.493373] Starting subtest: suspend-resume-fd
[467.965837] Subtest suspend-resume-fd: SUCCESS (1.299s)
[468.113679] [104/156] (556s left) gem_ctx_persistence (idempotent)
[468.184943] Starting subtest: idempotent
[468.187369] Subtest idempotent: SUCCESS (0.000s)
[468.319482] [105/156] (556s left) kms_big_fb (x-tiled-8bpp-rotate-270)
[468.425456] Starting subtest: x-tiled-8bpp-rotate-270
[468.446206] Subtest x-tiled-8bpp-rotate-270: SKIP (0.021s)
[468.560148] [106/156] (555s left) kms_psr (fbc-pr-suspend)
[468.658407] Starting subtest: fbc-pr-suspend
[468.662351] Subtest fbc-pr-suspend: SKIP (0.000s)
[468.741409] [107/156] (555s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-cpu)
[468.830679] Starting subtest: fbc-rgb565-draw-mmap-cpu
[472.822858] Subtest fbc-rgb565-draw-mmap-cpu: SUCCESS (4.066s)
[473.962166] [108/156] (550s left) kms_universal_plane (disable-primary-vs-flip)
[474.049408] Starting subtest: disable-primary-vs-flip
[474.051757] Starting dynamic subtest: pipe-A-eDP-1
[475.609168] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.576s)
[475.619902] Starting dynamic subtest: pipe-B-eDP-1
[477.847350] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.254s)
[477.858941] Starting dynamic subtest: pipe-C-eDP-1
[480.092922] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.260s)
[480.104443] Starting dynamic subtest: pipe-D-eDP-1
[482.267275] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.182s)
[482.269610] Subtest disable-primary-vs-flip: SUCCESS (8.276s)
[483.168057] [109/156] (541s left) kms_ccs (crc-primary-suspend-y-tiled-ccs)
[483.270080] Starting subtest: crc-primary-suspend-y-tiled-ccs
[483.272325] Starting dynamic subtest: pipe-A-eDP-1
[483.281294] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[483.294118] Starting dynamic subtest: pipe-B-eDP-1
[483.302832] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[483.313587] Starting dynamic subtest: pipe-C-eDP-1
[483.322274] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[483.332812] Starting dynamic subtest: pipe-D-eDP-1
[483.341593] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[483.345966] Subtest crc-primary-suspend-y-tiled-ccs: SKIP (0.005s)
[485.527800] [110/156] (539s left) kms_flip (flip-vs-expired-vblank-interruptible)
[485.634942] Starting subtest: flip-vs-expired-vblank-interruptible
[485.637325] Starting dynamic subtest: A-eDP1
[494.366245] Dynamic subtest A-eDP1: SUCCESS (8.712s)
[494.379563] Starting dynamic subtest: B-eDP1
[503.078387] Dynamic subtest B-eDP1: SUCCESS (8.713s)
[503.093656] Starting dynamic subtest: C-eDP1
[511.799902] Dynamic subtest C-eDP1: SUCCESS (8.706s)
[511.812977] Starting dynamic subtest: D-eDP1
[520.442141] Dynamic subtest D-eDP1: SUCCESS (8.641s)
[520.444781] Subtest flip-vs-expired-vblank-interruptible: SUCCESS (34.785s)
[521.562603] [111/156] (503s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[521.662521] Starting subtest: psrhdr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[521.667148] Subtest psrhdr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[521.757564] [112/156] (503s left) kms_cursor_crc (cursor-rapid-movement-64x64)
[521.934984] Starting subtest: cursor-rapid-movement-64x64
[521.940183] Starting dynamic subtest: pipe-A-eDP-1
[522.482419] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.542s)
[522.513162] Starting dynamic subtest: pipe-D-eDP-1
[523.679651] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.176s)
[523.682023] Subtest cursor-rapid-movement-64x64: SUCCESS (1.745s)
[524.549945] [113/156] (500s left) gem_exec_whisper (basic-contexts-forked)
[524.608979] Starting subtest: basic-contexts-forked
[547.982452] Subtest basic-contexts-forked: SUCCESS (23.380s)
[548.180698] [114/156] (476s left) gem_mmap_gtt (basic-read)
[548.240800] Subtest basic-read: SKIP (0.000s)
[548.324972] [115/156] (476s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-shrfb-draw-render)
[548.428478] Starting subtest: hdr-1p-offscreen-pri-shrfb-draw-render
[548.432916] Subtest hdr-1p-offscreen-pri-shrfb-draw-render: SKIP (0.000s)
[548.525154] [116/156] (476s left) gem_exec_fence (basic-await)
[548.699851] Starting subtest: basic-await
[548.702406] Starting dynamic subtest: rcs0
[548.777679] Dynamic subtest rcs0: SUCCESS (0.077s)
[548.795686] Starting dynamic subtest: bcs0
[548.851211] Dynamic subtest bcs0: SUCCESS (0.072s)
[548.865545] Starting dynamic subtest: vcs0
[548.924604] Dynamic subtest vcs0: SUCCESS (0.071s)
[548.939000] Starting dynamic subtest: vcs1
[548.997753] Dynamic subtest vcs1: SUCCESS (0.072s)
[549.012463] Starting dynamic subtest: vecs0
[549.072012] Dynamic subtest vecs0: SUCCESS (0.072s)
[549.086073] Starting dynamic subtest: ccs0
[549.146079] Dynamic subtest ccs0: SUCCESS (0.072s)
[549.148962] Subtest basic-await: SUCCESS (0.447s)
[550.538050] [117/156] (474s left) gem_exec_balancer (full-late-pulse)
[550.602568] Starting subtest: full-late-pulse
[555.329870] Subtest full-late-pulse: SUCCESS (4.729s)
[555.665920] [118/156] (469s left) kms_ccs (random-ccs-data-y-tiled-ccs)
[555.772426] Starting subtest: random-ccs-data-y-tiled-ccs
[555.774729] Starting dynamic subtest: pipe-A-eDP-1
[555.800284] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[555.814326] Starting dynamic subtest: pipe-B-eDP-1
[555.838563] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[555.849173] Starting dynamic subtest: pipe-C-eDP-1
[555.872730] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[555.882766] Starting dynamic subtest: pipe-D-eDP-1
[555.907520] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[555.912709] Subtest random-ccs-data-y-tiled-ccs: SKIP (0.005s)
[558.082684] [119/156] (466s left) gem_eio (wait-wedge-1us)
[558.314780] Starting subtest: wait-wedge-1us
[559.561582] Subtest wait-wedge-1us: SUCCESS (1.246s)
[559.704787] [120/156] (465s left) kms_plane_multiple (tiling-y)
[559.798353] Starting subtest: tiling-y
[559.802594] Subtest tiling-y: SKIP (0.000s)
[559.885355] [121/156] (465s left) gem_unref_active_buffers
[560.073240] [122/156] (465s left) kms_psr (psr-cursor-mmap-cpu)
[560.166311] Starting subtest: psr-cursor-mmap-cpu
[560.168632] Starting dynamic subtest: eDP-1
[561.867028] Dynamic subtest eDP-1: SUCCESS (1.702s)
[561.869421] Subtest psr-cursor-mmap-cpu: SUCCESS (1.703s)
[562.753248] [123/156] (462s left) gem_softpin (reverse)
[562.827028] Starting subtest: reverse
[562.831898] Subtest reverse: SUCCESS (0.005s)
[562.965579] [124/156] (462s left) dumb_buffer (map-uaf)
[563.024837] Starting subtest: map-uaf
[563.027001] Subtest map-uaf: SUCCESS (0.005s)
[563.139001] [125/156] (462s left) kms_pm_backlight (brightness-with-dpms)
[563.246757] Starting subtest: brightness-with-dpms
[563.295189] Starting dynamic subtest: eDP-1
[565.747654] Dynamic subtest eDP-1: SUCCESS (2.448s)
[565.749634] Subtest brightness-with-dpms: SUCCESS (2.498s)
[566.604869] [126/156] (458s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[566.703143] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[566.707742] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[566.809388] [127/156] (458s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[566.911947] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[566.916437] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[567.027340] [128/156] (458s left) kms_plane (pixel-format-y-tiled-modifier)
[567.130303] Starting subtest: pixel-format-y-tiled-modifier
[567.134650] Subtest pixel-format-y-tiled-modifier: SKIP (0.000s)
[567.204531] [129/156] (458s left) kms_cursor_crc (cursor-offscreen-32x10)
[567.374017] Starting subtest: cursor-offscreen-32x10
[567.425476] Subtest cursor-offscreen-32x10: SKIP (0.046s)
[567.728606] [130/156] (457s left) kms_big_fb (yf-tiled-16bpp-rotate-90)
[567.819270] Starting subtest: yf-tiled-16bpp-rotate-90
[567.823055] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.000s)
[567.920701] [131/156] (457s left) gem_ctx_persistence (engines-mixed-process)
[567.980702] Starting subtest: engines-mixed-process
[567.982668] Starting dynamic subtest: rcs0
[569.465043] Dynamic subtest rcs0: SUCCESS (1.483s)
[569.478192] Starting dynamic subtest: bcs0
[571.435603] Dynamic subtest bcs0: SUCCESS (1.967s)
[571.447398] Starting dynamic subtest: vcs0
[573.420072] Dynamic subtest vcs0: SUCCESS (1.982s)
[573.431481] Starting dynamic subtest: vcs1
[575.464976] Dynamic subtest vcs1: SUCCESS (2.043s)
[575.477134] Starting dynamic subtest: vecs0
[577.466009] Dynamic subtest vecs0: SUCCESS (2.001s)
[577.477731] Starting dynamic subtest: ccs0
[579.449149] Dynamic subtest ccs0: SUCCESS (1.982s)
[579.452096] Subtest engines-mixed-process: SUCCESS (11.465s)
[579.530295] [132/156] (446s left) kms_content_protection (dp-mst-type-0-suspend-resume)
[579.681293] Starting subtest: dp-mst-type-0-suspend-resume
[579.685286] Subtest dp-mst-type-0-suspend-resume: SKIP (0.000s)
[579.808203] [133/156] (445s left) gem_set_tiling_vs_blt (tiled-to-untiled)
[579.867393] Subtest tiled-to-untiled: SKIP (0.000s)
[579.944262] [134/156] (445s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-pgflip-blt)
[580.034202] Starting subtest: fbcpsr-1p-primscrn-shrfb-pgflip-blt
[587.520058] Subtest fbcpsr-1p-primscrn-shrfb-pgflip-blt: SUCCESS (7.492s)
[587.658238] [135/156] (438s left) gem_create (create-ext-placement-sanity-check)
[587.825171] Starting subtest: create-ext-placement-sanity-check
[587.831982] Subtest create-ext-placement-sanity-check: SUCCESS (0.004s)
[587.924466] [136/156] (437s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[588.026961] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[588.874115] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.848s)
[589.073244] [137/156] (436s left) gem_exec_schedule (reorder-wide)
[589.252155] Subtest reorder-wide: SKIP (0.000s)
[589.436875] [138/156] (436s left) gem_softpin (softpin)
[589.495556] Starting subtest: softpin
[621.854084] Subtest softpin: SUCCESS (32.338s)
[621.973180] [139/156] (403s left) gem_pxp (hw-rejects-pxp-context)
[622.078919] Starting subtest: hw-rejects-pxp-context
[622.083694] Subtest hw-rejects-pxp-context: SKIP (0.000s)
[622.191819] [140/156] (403s left) kms_dirtyfb (psr-dirtyfb-ioctl)
[622.290003] Starting subtest: psr-dirtyfb-ioctl
[622.291839] Starting dynamic subtest: A-eDP-1
[626.251997] Dynamic subtest A-eDP-1: SUCCESS (3.964s)
[626.254405] Subtest psr-dirtyfb-ioctl: SUCCESS (3.965s)
[627.128519] [141/156] (398s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-pwrite)
[627.235029] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-pwrite
[627.239286] Subtest fbc-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[627.332861] [142/156] (398s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-move)
[627.423259] Starting subtest: hdr-2p-primscrn-spr-indfb-move
[627.427660] Subtest hdr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[627.543673] [143/156] (398s left) gem_exec_flush (basic-uc-rw-default)
[627.600790] Starting subtest: basic-uc-rw-default
[633.083698] Subtest basic-uc-rw-default: SUCCESS (5.455s)
[633.261289] [144/156] (392s left) kms_prop_blob (invalid-set-prop)
[633.325840] Starting subtest: invalid-set-prop
[633.328328] Subtest invalid-set-prop: SUCCESS (0.000s)
[633.457148] [145/156] (392s left) i915_query (engine-info-invalid)
[633.505277] Starting subtest: engine-info-invalid
[633.507497] Subtest engine-info-invalid: SUCCESS (0.000s)
[633.608283] [146/156] (392s left) gem_exec_reloc (basic-wc-gtt-noreloc)
[633.666077] Subtest basic-wc-gtt-noreloc: SKIP (0.000s)
[633.747230] [147/156] (392s left) i915_hangman (gt-engine-hang)
[633.808651] Starting subtest: gt-engine-hang
[633.810826] Starting dynamic subtest: rcs0
[635.418596] Dynamic subtest rcs0: SUCCESS (1.609s)
[635.432349] Starting dynamic subtest: bcs0
[637.402730] Dynamic subtest bcs0: SUCCESS (1.982s)
[637.417131] Starting dynamic subtest: vcs0
[639.451580] Dynamic subtest vcs0: SUCCESS (2.046s)
[639.468592] Starting dynamic subtest: vcs1
[641.434652] Dynamic subtest vcs1: SUCCESS (1.981s)
[641.449263] Starting dynamic subtest: vecs0
[643.353730] Dynamic subtest vecs0: SUCCESS (1.917s)
[643.368401] Starting dynamic subtest: ccs0
[645.402287] Dynamic subtest ccs0: SUCCESS (2.046s)
[645.405275] Subtest gt-engine-hang: SUCCESS (11.598s)
[645.488413] [148/156] (380s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-gtt)
[645.606865] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-gtt
[645.611186] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[645.704851] [149/156] (380s left) sw_sync (sync_multi_producer_single_consumer)
[645.741258] Starting subtest: sync_multi_producer_single_consumer
[646.813390] Subtest sync_multi_producer_single_consumer: SUCCESS (1.075s)
[646.878624] [150/156] (379s left) kms_atomic_interruptible (atomic-setmode)
[646.967149] Starting subtest: atomic-setmode
[646.969387] Starting dynamic subtest: pipe-A-eDP-1
[653.861600] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.896s)
[653.864053] Subtest atomic-setmode: SUCCESS (6.897s)
[654.674698] [151/156] (371s left) prime_vgem (shrink)
[654.750677] Starting subtest: shrink
[654.778432] Subtest shrink: SUCCESS (0.026s)
[654.893376] [152/156] (371s left) gem_eio (create)
[655.075155] Starting subtest: create
[655.341826] Subtest create: SUCCESS (0.266s)
[655.463716] [153/156] (371s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-fullscreen)
[655.558981] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-fullscreen
[655.563004] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[655.659425] [154/156] (370s left) kms_frontbuffer_tracking (fbcpsr-tiling-y)
[655.760034] Starting subtest: fbcpsr-tiling-y
[655.763452] Subtest fbcpsr-tiling-y: SKIP (0.000s)
[655.866915] [155/156] (370s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-signaled)
[655.928798] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-signaled
[656.030658] Subtest etime-multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.101s)
[656.122056] [156/156] (370s left) syncobj_wait (invalid-signal-zero-handles)
[656.171808] Starting subtest: invalid-signal-zero-handles
[656.173812] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[656.271060] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: kms_chamelium_hpd
results: parsing output: 2/ for test: kms_async_flips
results: parsing output: 3/ for test: gem_exec_fence
results: parsing output: 4/ for test: kms_big_fb
results: parsing output: 5/ for test: kms_rotation_crc
results: parsing output: 6/ for test: kms_addfb_basic
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: kms_pm_rpm
results: parsing output: 10/ for test: kms_flip_scaled_crc
results: parsing output: 11/ for test: kms_ccs
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: syncobj_timeline
results: parsing output: 14/ for test: i915_drm_fdinfo
results: parsing output: 15/ for test: syncobj_wait
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: kms_flip
results: parsing output: 18/ for test: kms_psr
results: parsing output: 19/ for test: kms_flip
results: parsing output: 20/ for test: syncobj_wait
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: gem_exec_params
results: parsing output: 27/ for test: gem_exec_reloc
results: parsing output: 28/ for test: kms_busy
results: parsing output: 29/ for test: kms_big_fb
results: parsing output: 30/ for test: syncobj_timeline
results: parsing output: 31/ for test: api_intel_bb
results: parsing output: 32/ for test: kms_psr
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: gem_pxp
results: parsing output: 35/ for test: kms_pipe_crc_basic
results: parsing output: 36/ for test: kms_chamelium_frames
results: parsing output: 37/ for test: kms_flip_scaled_crc
results: parsing output: 38/ for test: kms_psr
results: parsing output: 39/ for test: gem_eio
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: perf_pmu
results: parsing output: 43/ for test: drm_read
results: parsing output: 44/ for test: kms_big_fb
results: parsing output: 45/ for test: api_intel_bb
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: kms_ccs
results: parsing output: 48/ for test: gem_softpin
results: parsing output: 49/ for test: i915_pm_rps
results: parsing output: 50/ for test: gem_lmem_swapping
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: gem_wait
results: parsing output: 53/ for test: kms_pm_rpm
results: parsing output: 54/ for test: kms_vrr
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: gem_ctx_bad_destroy
results: parsing output: 57/ for test: kms_properties
results: parsing output: 58/ for test: kms_psr2_sf
results: parsing output: 59/ for test: perf_pmu
results: parsing output: 60/ for test: syncobj_timeline
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: kms_flip
results: parsing output: 63/ for test: kms_explicit_fence
results: parsing output: 64/ for test: kms_frontbuffer_tracking
results: parsing output: 65/ for test: syncobj_timeline
results: parsing output: 66/ for test: gem_request_retire
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: perf_pmu
results: parsing output: 70/ for test: gem_lmem_swapping
results: parsing output: 71/ for test: gem_ringfill
results: parsing output: 72/ for test: gen7_exec_parse
results: parsing output: 73/ for test: kms_cursor_legacy
results: parsing output: 74/ for test: kms_lease
results: parsing output: 75/ for test: kms_rotation_crc
results: parsing output: 76/ for test: kms_color
results: parsing output: 77/ for test: syncobj_wait
results: parsing output: 78/ for test: syncobj_wait
results: parsing output: 79/ for test: gem_ctx_param
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_mmap_gtt
results: parsing output: 83/ for test: gem_exec_params
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: fbdev
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: gem_exec_params
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: vgem_basic
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: kms_plane_scaling
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: kms_plane_cursor
results: parsing output: 94/ for test: kms_rotation_crc
results: parsing output: 95/ for test: kms_cursor_edge_walk
results: parsing output: 96/ for test: kms_ccs
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: gem_exec_schedule
results: parsing output: 102/ for test: gem_workarounds
results: parsing output: 103/ for test: gem_ctx_persistence
results: parsing output: 104/ for test: kms_big_fb
results: parsing output: 105/ for test: kms_psr
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_universal_plane
results: parsing output: 108/ for test: kms_ccs
results: parsing output: 109/ for test: kms_flip
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: kms_cursor_crc
results: parsing output: 112/ for test: gem_exec_whisper
results: parsing output: 113/ for test: gem_mmap_gtt
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: gem_exec_fence
results: parsing output: 116/ for test: gem_exec_balancer
results: parsing output: 117/ for test: kms_ccs
results: parsing output: 118/ for test: gem_eio
results: parsing output: 119/ for test: kms_plane_multiple
results: parsing output: 120/ for test: gem_unref_active_buffers
results: parsing output: 121/ for test: kms_psr
results: parsing output: 122/ for test: gem_softpin
results: parsing output: 123/ for test: dumb_buffer
results: parsing output: 124/ for test: kms_pm_backlight
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: kms_big_fb
results: parsing output: 127/ for test: kms_plane
results: parsing output: 128/ for test: kms_cursor_crc
results: parsing output: 129/ for test: kms_big_fb
results: parsing output: 130/ for test: gem_ctx_persistence
results: parsing output: 131/ for test: kms_content_protection
results: parsing output: 132/ for test: gem_set_tiling_vs_blt
results: parsing output: 133/ for test: kms_frontbuffer_tracking
results: parsing output: 134/ for test: gem_create
results: parsing output: 135/ for test: kms_big_fb
results: parsing output: 136/ for test: gem_exec_schedule
results: parsing output: 137/ for test: gem_softpin
results: parsing output: 138/ for test: gem_pxp
results: parsing output: 139/ for test: kms_dirtyfb
results: parsing output: 140/ for test: kms_frontbuffer_tracking
results: parsing output: 141/ for test: kms_frontbuffer_tracking
results: parsing output: 142/ for test: gem_exec_flush
results: parsing output: 143/ for test: kms_prop_blob
results: parsing output: 144/ for test: i915_query
results: parsing output: 145/ for test: gem_exec_reloc
results: parsing output: 146/ for test: i915_hangman
results: parsing output: 147/ for test: kms_frontbuffer_tracking
results: parsing output: 148/ for test: sw_sync
results: parsing output: 149/ for test: kms_atomic_interruptible
results: parsing output: 150/ for test: prime_vgem
results: parsing output: 151/ for test: gem_eio
results: parsing output: 152/ for test: kms_frontbuffer_tracking
results: parsing output: 153/ for test: kms_frontbuffer_tracking
results: parsing output: 154/ for test: syncobj_timeline
results: parsing output: 155/ for test: syncobj_wait
Done.