[57.245838] Initializing watchdogs
[57.245884]   /dev/watchdog0
[57.260091] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[57.264658] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.266914] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.270219] [001/133] (960s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-fullscreen)
[57.399756] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-fullscreen
[57.404154] Subtest fbcpsr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[57.489221] [002/133] (960s left) kms_flip (2x-blocking-absolute-wf_vblank-interruptible)
[57.585643] Starting subtest: 2x-blocking-absolute-wf_vblank-interruptible
[57.596518] Subtest 2x-blocking-absolute-wf_vblank-interruptible: SKIP (0.010s)
[57.717198] [003/133] (960s left) kms_cursor_legacy (short-flip-after-cursor-toggle)
[57.830967] Starting subtest: short-flip-after-cursor-toggle
[58.821614] Subtest short-flip-after-cursor-toggle: SUCCESS (0.989s)
[59.619167] [004/133] (958s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[59.725083] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[60.515514] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.792s)
[60.686789] [005/133] (957s left) kms_flip (2x-flip-vs-blocking-wf-vblank)
[60.783673] Starting subtest: 2x-flip-vs-blocking-wf-vblank
[60.791999] Subtest 2x-flip-vs-blocking-wf-vblank: SKIP (0.010s)
[60.865211] [006/133] (957s left) gem_ctx_persistence (hostile)
[60.926414] Starting subtest: hostile
[71.082199] Subtest hostile: SUCCESS (10.151s)
[71.169908] [007/133] (946s left) core_auth (getclient-simple)
[71.206542] Starting subtest: getclient-simple
[71.231095] Subtest getclient-simple: SUCCESS (0.025s)
[71.329650] [008/133] (946s left) vgem_basic (bad-handle)
[71.408135] Starting subtest: bad-handle
[71.410399] Subtest bad-handle: SUCCESS (0.000s)
[71.461053] [FACT vgem_basic (bad-handle)] new: kernel.kmod_is_loaded.vgem: true
[71.463976] [009/133] (946s left) gem_ctx_bad_destroy (invalid-ctx)
[71.523230] Starting subtest: invalid-ctx
[71.525178] Subtest invalid-ctx: SUCCESS (0.000s)
[71.591627] [010/133] (946s left) syncobj_wait (wait-all-interrupted)
[71.646915] Starting subtest: wait-all-interrupted
[71.749069] Subtest wait-all-interrupted: SUCCESS (0.102s)
[71.841614] [011/133] (946s left) kms_flip (basic-flip-vs-wf_vblank)
[71.932623] Starting subtest: basic-flip-vs-wf_vblank
[71.934956] Starting dynamic subtest: A-eDP1
[73.868864] Dynamic subtest A-eDP1: SUCCESS (1.936s)
[73.878710] Starting dynamic subtest: B-eDP1
[75.807483] Dynamic subtest B-eDP1: SUCCESS (1.937s)
[75.817832] Starting dynamic subtest: C-eDP1
[77.723202] Dynamic subtest C-eDP1: SUCCESS (1.915s)
[77.733116] Starting dynamic subtest: D-eDP1
[79.655875] Dynamic subtest D-eDP1: SUCCESS (1.930s)
[79.658762] Subtest basic-flip-vs-wf_vblank: SUCCESS (7.727s)
[81.106500] [012/133] (937s left) gem_render_copy (y-tiled-mc-ccs-to-y-tiled-ccs)
[81.168075] Starting subtest: y-tiled-mc-ccs-to-y-tiled-ccs
[81.172586] Subtest y-tiled-mc-ccs-to-y-tiled-ccs: SKIP (0.000s)
[81.365820] [013/133] (936s left) gem_mmap_gtt (basic-read-write-distinct)
[81.421351] Subtest basic-read-write-distinct: SKIP (0.000s)
[81.486465] [014/133] (936s left) gem_fenced_exec_thrash (too-many-fences)
[81.539246] Subtest too-many-fences: SKIP (0.000s)
[81.607294] [015/133] (936s left) gem_spin_batch (spin-each)
[81.661547] Starting subtest: spin-each
[84.926695] Subtest spin-each: SUCCESS (3.260s)
[86.963516] [016/133] (931s left) kms_plane (plane-position-covered)
[87.081936] Starting subtest: plane-position-covered
[87.581842] Starting dynamic subtest: pipe-A-plane-1
[88.860245] Dynamic subtest pipe-A-plane-1: SUCCESS (1.280s)
[88.870313] Starting dynamic subtest: pipe-A-plane-2
[90.126821] Dynamic subtest pipe-A-plane-2: SUCCESS (1.266s)
[90.136412] Starting dynamic subtest: pipe-A-plane-3
[91.435618] Dynamic subtest pipe-A-plane-3: SUCCESS (1.306s)
[91.445253] Starting dynamic subtest: pipe-A-plane-4
[92.680957] Dynamic subtest pipe-A-plane-4: SUCCESS (1.246s)
[92.690553] Starting dynamic subtest: pipe-A-plane-5
[92.705251] Dynamic subtest pipe-A-plane-5: SUCCESS (0.024s)
[93.910495] Starting dynamic subtest: pipe-B-plane-1
[95.152981] Dynamic subtest pipe-B-plane-1: SUCCESS (1.248s)
[95.162547] Starting dynamic subtest: pipe-B-plane-2
[96.476136] Dynamic subtest pipe-B-plane-2: SUCCESS (1.322s)
[96.485505] Starting dynamic subtest: pipe-B-plane-3
[97.729873] Dynamic subtest pipe-B-plane-3: SUCCESS (1.254s)
[97.740480] Starting dynamic subtest: pipe-B-plane-4
[99.027210] Dynamic subtest pipe-B-plane-4: SUCCESS (1.297s)
[99.039055] Starting dynamic subtest: pipe-B-plane-5
[99.054764] Dynamic subtest pipe-B-plane-5: SUCCESS (0.025s)
[99.056982] Subtest plane-position-covered: SUCCESS (11.973s)
[99.855171] [017/133] (918s left) kms_vblank (query-busy-hang)
[99.959297] Starting subtest: query-busy-hang
[99.961200] Starting dynamic subtest: pipe-A-eDP-1
[127.643982] Dynamic subtest pipe-A-eDP-1: SUCCESS (27.684s)
[127.657179] Starting dynamic subtest: pipe-D-eDP-1
[152.663420] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.015s)
[152.665699] Subtest query-busy-hang: SUCCESS (52.704s)
[153.533885] [018/133] (864s left) syncobj_timeline (multi-wait-all-signaled)
[153.612945] Starting subtest: multi-wait-all-signaled
[153.615337] Subtest multi-wait-all-signaled: SUCCESS (0.002s)
[153.718622] [019/133] (864s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted)
[153.786397] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted
[153.889702] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.103s)
[153.967408] [020/133] (864s left) kms_vblank (wait-forked-busy)
[154.057082] Starting subtest: wait-forked-busy
[154.059034] Starting dynamic subtest: pipe-A-eDP-1
[155.944211] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.886s)
[155.958071] Starting dynamic subtest: pipe-D-eDP-1
[158.459463] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.513s)
[158.461781] Subtest wait-forked-busy: SUCCESS (4.402s)
[159.266263] [021/133] (859s left) perf_pmu (semaphore-wait-idle)
[159.311893] Starting subtest: semaphore-wait-idle
[159.313816] Starting dynamic subtest: rcs0
[159.826426] Dynamic subtest rcs0: SUCCESS (0.513s)
[159.836804] Starting dynamic subtest: bcs0
[160.339892] Dynamic subtest bcs0: SUCCESS (0.511s)
[160.350733] Starting dynamic subtest: vcs0
[160.852646] Dynamic subtest vcs0: SUCCESS (0.511s)
[160.863315] Starting dynamic subtest: vcs1
[161.365363] Dynamic subtest vcs1: SUCCESS (0.511s)
[161.376035] Starting dynamic subtest: vecs0
[161.878845] Dynamic subtest vecs0: SUCCESS (0.511s)
[161.889848] Starting dynamic subtest: ccs0
[162.391711] Dynamic subtest ccs0: SUCCESS (0.512s)
[162.394662] Subtest semaphore-wait-idle: SUCCESS (3.080s)
[162.508767] [022/133] (856s left) kms_addfb_basic (unused-handle)
[162.557087] Starting subtest: unused-handle
[162.559093] Subtest unused-handle: SUCCESS (0.000s)
[162.701342] [023/133] (855s left) gen9_exec_parse (batch-zero-length)
[162.758518] Subtest batch-zero-length: SKIP (0.000s)
[162.822379] [024/133] (855s left) gem_eio (throttle)
[163.009811] Starting subtest: throttle
[163.247842] Subtest throttle: SUCCESS (0.239s)
[163.357265] [025/133] (855s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-cpu)
[163.445760] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-cpu
[163.449166] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[163.523890] [026/133] (855s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-render)
[163.622322] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-render
[167.860322] Subtest fbc-1p-offscreen-pri-shrfb-draw-render: SUCCESS (4.240s)
[169.000720] [027/133] (849s left) kms_ccs (random-ccs-data-4-tiled-mtl-rc-ccs-cc)
[169.103100] Starting subtest: random-ccs-data-4-tiled-mtl-rc-ccs-cc
[169.105281] Starting dynamic subtest: pipe-A-eDP-1
[169.805472] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.696s)
[169.814414] Starting dynamic subtest: pipe-B-eDP-1
[171.605102] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.798s)
[171.614392] Starting dynamic subtest: pipe-C-eDP-1
[173.356320] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.750s)
[173.365084] Starting dynamic subtest: pipe-D-eDP-1
[175.188469] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.831s)
[175.190356] Subtest random-ccs-data-4-tiled-mtl-rc-ccs-cc: SUCCESS (6.080s)
[176.326455] [028/133] (842s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[176.427985] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[176.442208] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.013s)
[176.534967] [029/133] (842s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[176.631894] Starting subtest: compare-crc-sanitycheck-xr24
[176.634107] Starting dynamic subtest: pipe-A-eDP-1
[177.246459] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.615s)
[177.256852] Starting dynamic subtest: pipe-B-eDP-1
[178.528206] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.279s)
[178.537826] Starting dynamic subtest: pipe-C-eDP-1
[179.781927] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.253s)
[179.792783] Starting dynamic subtest: pipe-D-eDP-1
[181.014425] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.233s)
[181.016685] Subtest compare-crc-sanitycheck-xr24: SUCCESS (4.385s)
[181.843964] [030/133] (837s left) dumb_buffer (create-clear)
[181.909016] Starting subtest: create-clear
[216.472764] Subtest create-clear: SUCCESS (34.559s)
[216.614708] [031/133] (802s left) kms_psr (fbc-psr2-sprite-mmap-gtt)
[216.710962] Starting subtest: fbc-psr2-sprite-mmap-gtt
[216.713502] Starting dynamic subtest: eDP-1
[216.717787] Dynamic subtest eDP-1: SKIP (0.000s)
[216.722087] Subtest fbc-psr2-sprite-mmap-gtt: SKIP (0.001s)
[216.793533] [032/133] (802s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset-interruptible)
[216.879168] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset-interruptible
[216.889618] Subtest 2x-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.010s)
[216.982288] [033/133] (802s left) gem_exec_schedule (pi-ringfull)
[217.167063] Starting subtest: pi-ringfull
[217.169400] Starting dynamic subtest: rcs0
[217.196299] Dynamic subtest rcs0: SUCCESS (0.032s)
[217.205661] Starting dynamic subtest: bcs0
[217.228296] Dynamic subtest bcs0: SUCCESS (0.031s)
[217.240381] Starting dynamic subtest: vcs0
[217.258182] Dynamic subtest vcs0: SUCCESS (0.029s)
[217.271235] Starting dynamic subtest: vcs1
[217.286316] Dynamic subtest vcs1: SUCCESS (0.028s)
[217.299333] Starting dynamic subtest: vecs0
[217.313287] Dynamic subtest vecs0: SUCCESS (0.026s)
[217.326445] Starting dynamic subtest: ccs0
[217.340189] Dynamic subtest ccs0: SUCCESS (0.026s)
[217.342331] Subtest pi-ringfull: SUCCESS (0.177s)
[217.572323] [034/133] (801s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite)
[217.663293] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite
[217.667525] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[217.743490] [035/133] (801s left) kms_cursor_edge_walk (64x64-right-edge)
[217.853679] Starting subtest: 64x64-right-edge
[217.855963] Starting dynamic subtest: pipe-A-eDP-1
[221.355055] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.501s)
[221.364677] Starting dynamic subtest: pipe-D-eDP-1
[224.530258] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.173s)
[224.532677] Subtest 64x64-right-edge: SUCCESS (6.676s)
[225.342342] [036/133] (793s left) gem_mmap_wc (bad-size)
[225.409954] Subtest bad-size: SKIP (0.000s)
[225.472582] [037/133] (793s left) api_intel_allocator (gem-pool)
[225.524521] Starting subtest: gem-pool
[225.530309] Subtest gem-pool: SUCCESS (0.009s)
[225.662973] [038/133] (793s left) kms_psr2_sf (pr-plane-move-sf-dmg-area)
[225.755693] Starting subtest: pr-plane-move-sf-dmg-area
[225.760191] Subtest pr-plane-move-sf-dmg-area: SKIP (0.000s)
[225.825371] [039/133] (793s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-render)
[225.934734] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-render
[230.301036] Subtest fbc-1p-primscrn-pri-indfb-draw-render: SUCCESS (4.369s)
[231.448067] [040/133] (787s left) kms_frontbuffer_tracking (psr-rgb101010-draw-blt)
[231.561482] Starting subtest: psr-rgb101010-draw-blt
[238.789892] Subtest psr-rgb101010-draw-blt: SUCCESS (7.230s)
[238.915245] [041/133] (780s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc)
[239.019418] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc
[246.095790] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (7.079s)
[246.219910] [042/133] (773s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-20x20)
[246.328092] Starting subtest: planes-downscale-factor-0-75-upscale-20x20
[246.330497] Starting dynamic subtest: pipe-A
[246.393520] Dynamic subtest pipe-A: SUCCESS (0.065s)
[246.414036] Starting dynamic subtest: pipe-B
[247.610121] Dynamic subtest pipe-B: SUCCESS (1.217s)
[247.620668] Starting dynamic subtest: pipe-C
[248.717199] Dynamic subtest pipe-C: SUCCESS (1.106s)
[248.727839] Starting dynamic subtest: pipe-D
[249.851454] Dynamic subtest pipe-D: SUCCESS (1.134s)
[249.853836] Subtest planes-downscale-factor-0-75-upscale-20x20: SUCCESS (3.527s)
[251.008685] [043/133] (768s left) gem_gpgpu_fill (basic)
[251.062178] Starting subtest: basic
[251.064100] Starting dynamic subtest: smem
[251.068821] Dynamic subtest smem: SUCCESS (0.007s)
[251.071114] Subtest basic: SUCCESS (0.008s)
[251.219104] [044/133] (768s left) kms_atomic_interruptible (universal-setplane-primary)
[251.305807] Starting subtest: universal-setplane-primary
[251.307898] Starting dynamic subtest: pipe-A-eDP-1
[258.740080] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.433s)
[258.742392] Subtest universal-setplane-primary: SUCCESS (7.435s)
[259.577665] [045/133] (760s left) gem_exec_fence (long-history)
[259.994136] Starting subtest: long-history
[267.641386] Subtest long-history: SUCCESS (7.648s)
[269.134480] [046/133] (750s left) kms_rotation_crc (primary-y-tiled-reflect-x-90)
[269.228750] Starting subtest: primary-y-tiled-reflect-x-90
[269.233048] Subtest primary-y-tiled-reflect-x-90: SKIP (0.001s)
[269.301478] [047/133] (750s left) gem_close_race (multigpu-basic-process)
[269.360177] Starting subtest: multigpu-basic-process
[269.364613] Subtest multigpu-basic-process: SKIP (0.000s)
[269.604689] [048/133] (750s left) kms_draw_crc (draw-method-pwrite)
[269.692746] Starting subtest: draw-method-pwrite
[269.694608] Starting dynamic subtest: xrgb8888-untiled
[270.293327] Dynamic subtest xrgb8888-untiled: SUCCESS (0.600s)
[270.302053] Starting dynamic subtest: rgb565-untiled
[270.714132] Dynamic subtest rgb565-untiled: SUCCESS (0.421s)
[270.722954] Starting dynamic subtest: xrgb2101010-untiled
[271.225042] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.511s)
[271.233647] Starting dynamic subtest: xbgr16161616f-untiled
[271.959340] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.732s)
[271.968315] Starting dynamic subtest: xrgb8888-xtiled
[273.647765] Dynamic subtest xrgb8888-xtiled: SUCCESS (1.688s)
[273.658022] Starting dynamic subtest: rgb565-xtiled
[274.658875] Dynamic subtest rgb565-xtiled: SUCCESS (1.010s)
[274.667708] Starting dynamic subtest: xrgb2101010-xtiled
[276.347824] Dynamic subtest xrgb2101010-xtiled: SUCCESS (1.688s)
[276.356780] Starting dynamic subtest: xbgr16161616f-xtiled
[279.526256] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (3.177s)
[279.534558] Starting dynamic subtest: xrgb8888-4tiled
[280.570114] Dynamic subtest xrgb8888-4tiled: SUCCESS (1.044s)
[280.581387] Starting dynamic subtest: rgb565-4tiled
[281.148748] Dynamic subtest rgb565-4tiled: SUCCESS (0.577s)
[281.159456] Starting dynamic subtest: xrgb2101010-4tiled
[282.193264] Dynamic subtest xrgb2101010-4tiled: SUCCESS (1.043s)
[282.206816] Starting dynamic subtest: xbgr16161616f-4tiled
[285.070727] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (2.876s)
[285.073095] Subtest draw-method-pwrite: SUCCESS (15.379s)
[285.176485] [049/133] (734s left) gem_exec_schedule (fairslice-all)
[285.257025] Starting subtest: fairslice-all
[287.311965] Subtest fairslice-all: SUCCESS (2.064s)
[287.625301] [050/133] (732s left) gem_ctx_param (invalid-param-get)
[287.692340] Starting subtest: invalid-param-get
[287.694606] Subtest invalid-param-get: SUCCESS (0.002s)
[287.796494] [051/133] (732s left) kms_pm_rpm (modeset-lpsp-stress-no-wait)
[290.039883] Starting subtest: modeset-lpsp-stress-no-wait
[299.689868] Subtest modeset-lpsp-stress-no-wait: SUCCESS (9.651s)
[299.771413] [052/133] (720s left) i915_drm_fdinfo (virtual-idle)
[299.817542] Starting subtest: virtual-idle
[302.879467] Subtest virtual-idle: SUCCESS (3.058s)
[302.951655] [053/133] (717s left) gem_userptr_blits (set-cache-level)
[303.014701] Starting subtest: set-cache-level
[303.019441] Subtest set-cache-level: SKIP (0.000s)
[303.156229] [054/133] (716s left) kms_ccs (missing-ccs-buffer-yf-tiled-ccs)
[303.244906] Starting subtest: missing-ccs-buffer-yf-tiled-ccs
[303.247215] Starting dynamic subtest: pipe-A-eDP-1
[303.269664] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[303.279745] Starting dynamic subtest: pipe-B-eDP-1
[303.300363] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[303.308488] Starting dynamic subtest: pipe-C-eDP-1
[303.329223] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[303.337218] Starting dynamic subtest: pipe-D-eDP-1
[303.359679] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[303.364082] Subtest missing-ccs-buffer-yf-tiled-ccs: SKIP (0.004s)
[305.527289] [055/133] (714s left) gem_eio (wait-10ms)
[305.753493] Starting subtest: wait-10ms
[306.120145] Subtest wait-10ms: SUCCESS (0.369s)
[306.242964] [056/133] (713s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-pwrite)
[306.343852] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-pwrite
[315.607101] Subtest psr-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (9.266s)
[315.722767] [057/133] (704s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[315.846733] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[316.816393] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SUCCESS (0.970s)
[316.916626] [058/133] (703s left) perf (i915-ref-count)
[316.985302] Starting subtest: i915-ref-count
[317.058086] Subtest i915-ref-count: SUCCESS (0.070s)
[317.113547] [059/133] (703s left) gem_tiled_blits (interruptible)
[317.169614] Subtest interruptible: SKIP (0.000s)
[317.274050] [060/133] (702s left) syncobj_timeline (wait-all-snapshot)
[317.320472] Starting subtest: wait-all-snapshot
[317.400834] Subtest wait-all-snapshot: SUCCESS (0.081s)
[317.473730] [061/133] (702s left) perf_pmu (busy-double-start)
[317.515428] Starting subtest: busy-double-start
[317.517160] Starting dynamic subtest: rcs0
[321.158951] Dynamic subtest rcs0: FAIL (3.593s)
[321.168571] Starting dynamic subtest: bcs0
[324.692839] Dynamic subtest bcs0: SUCCESS (3.583s)
[324.704060] Starting dynamic subtest: vcs0
[328.404737] Dynamic subtest vcs0: SUCCESS (3.709s)
[328.415472] Starting dynamic subtest: vcs1
[332.461646] Dynamic subtest vcs1: SUCCESS (4.055s)
[332.472790] Starting dynamic subtest: vecs0
[336.404765] Dynamic subtest vecs0: SUCCESS (3.941s)
[336.415229] Starting dynamic subtest: ccs0
[340.037338] Dynamic subtest ccs0: FAIL (3.563s)
[340.039245] Subtest busy-double-start: FAIL (22.455s)
[340.080779] [062/133] (680s left) kms_lease (lease-unleased-crtc)
[340.180961] Starting subtest: lease-unleased-crtc
[340.183292] Starting dynamic subtest: pipe-A-eDP-1
[340.468083] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.287s)
[340.478484] Starting dynamic subtest: pipe-B-eDP-1
[341.208935] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.736s)
[341.220205] Starting dynamic subtest: pipe-C-eDP-1
[341.222326] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.004s)
[341.230935] Starting dynamic subtest: pipe-D-eDP-1
[341.233138] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.004s)
[341.235376] Subtest lease-unleased-crtc: SUCCESS (1.038s)
[341.583804] [063/133] (678s left) kms_ccs (bad-pixel-format-y-tiled-gen12-mc-ccs)
[341.682921] Starting subtest: bad-pixel-format-y-tiled-gen12-mc-ccs
[341.685098] Starting dynamic subtest: pipe-A-eDP-1
[341.707013] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[341.716633] Starting dynamic subtest: pipe-B-eDP-1
[341.737101] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[341.745343] Starting dynamic subtest: pipe-C-eDP-1
[341.765750] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[341.774159] Starting dynamic subtest: pipe-D-eDP-1
[341.795094] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[341.798903] Subtest bad-pixel-format-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[343.851592] [064/133] (676s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-render)
[343.945137] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-render
[350.794922] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-render: SUCCESS (6.852s)
[350.945965] [065/133] (669s left) kms_plane (pixel-format-4-tiled-modifier-source-clamping)
[351.043462] Starting subtest: pixel-format-4-tiled-modifier-source-clamping
[351.069599] Starting dynamic subtest: pipe-A-plane-0
[351.758553] Dynamic subtest pipe-A-plane-0: SUCCESS (0.689s)
[351.768344] Starting dynamic subtest: pipe-A-plane-3
[352.158118] Dynamic subtest pipe-A-plane-3: SUCCESS (0.398s)
[353.260551] Starting dynamic subtest: pipe-B-plane-0
[353.954665] Dynamic subtest pipe-B-plane-0: SUCCESS (0.700s)
[353.968558] Starting dynamic subtest: pipe-B-plane-3
[354.386627] Dynamic subtest pipe-B-plane-3: SUCCESS (0.433s)
[354.708056] Subtest pixel-format-4-tiled-modifier-source-clamping: SUCCESS (3.664s)
[355.540294] [066/133] (664s left) kms_atomic_transition (plane-use-after-nonblocking-unbind)
[355.650079] Starting subtest: plane-use-after-nonblocking-unbind
[355.652299] Starting dynamic subtest: pipe-A-eDP-1
[355.708764] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.061s)
[356.002955] Starting dynamic subtest: pipe-B-eDP-1
[356.839810] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.846s)
[357.135896] Subtest plane-use-after-nonblocking-unbind: SUCCESS (1.486s)
[357.998227] [067/133] (662s left) gem_tiled_fence_blits (basic)
[358.065461] Subtest basic: SKIP (0.000s)
[358.133765] [068/133] (662s left) syncobj_timeline (invalid-multi-wait-unsubmitted)
[358.204861] Starting subtest: invalid-multi-wait-unsubmitted
[358.207120] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[358.284860] [069/133] (662s left) i915_query (test-query-geometry-subslices)
[358.342976] Starting subtest: test-query-geometry-subslices
[358.344960] Subtest test-query-geometry-subslices: SUCCESS (0.000s)
[358.440539] [070/133] (662s left) kms_cursor_legacy (forked-move)
[358.554519] Starting subtest: forked-move
[358.556784] Starting dynamic subtest: pipe-A
[364.009614] Dynamic subtest pipe-A: SUCCESS (5.452s)
[364.021745] Starting dynamic subtest: pipe-B
[369.492167] Dynamic subtest pipe-B: SUCCESS (5.480s)
[369.513312] Starting dynamic subtest: pipe-C
[375.015777] Dynamic subtest pipe-C: SUCCESS (5.521s)
[375.027040] Starting dynamic subtest: pipe-D
[380.504481] Dynamic subtest pipe-D: SUCCESS (5.486s)
[380.513587] Starting dynamic subtest: all-pipes
[386.023777] Dynamic subtest all-pipes: SUCCESS (5.516s)
[386.026290] Subtest forked-move: SUCCESS (27.467s)
[386.183422] [071/133] (634s left) kms_busy (extended-pageflip-hang-newfb)
[386.279372] Starting subtest: extended-pageflip-hang-newfb
[386.281746] Starting dynamic subtest: pipe-A
[412.733333] Dynamic subtest pipe-A: SUCCESS (26.452s)
[412.742684] Starting dynamic subtest: pipe-D
[427.688062] Dynamic subtest pipe-D: SUCCESS (14.953s)
[427.690439] Subtest extended-pageflip-hang-newfb: SUCCESS (41.409s)
[428.561053] [072/133] (592s left) gem_tiling_max_stride
[428.685034] [073/133] (592s left) kms_flip (2x-flip-vs-wf_vblank)
[428.775127] Starting subtest: 2x-flip-vs-wf_vblank
[428.786024] Subtest 2x-flip-vs-wf_vblank: SKIP (0.010s)
[428.863190] [074/133] (592s left) vgem_basic (dmabuf-export)
[428.906514] Starting subtest: dmabuf-export
[428.924749] Subtest dmabuf-export: SUCCESS (0.022s)
[429.019062] [075/133] (591s left) kms_psr (psr-cursor-mmap-cpu)
[429.105134] Starting subtest: psr-cursor-mmap-cpu
[429.107323] Starting dynamic subtest: eDP-1
[430.853257] Dynamic subtest eDP-1: SUCCESS (1.748s)
[430.855637] Subtest psr-cursor-mmap-cpu: SUCCESS (1.749s)
[431.717591] [076/133] (589s left) gem_mmap_gtt (basic-small-bo-tiledx)
[431.788648] Subtest basic-small-bo-tiledX: SKIP (0.000s)
[431.847706] [077/133] (589s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[431.946497] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc
[431.950596] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[432.043692] [078/133] (588s left) kms_rotation_crc (primary-rotation-90)
[432.145213] Starting subtest: primary-rotation-90
[432.149644] Subtest primary-rotation-90: SKIP (0.001s)
[432.218692] [079/133] (588s left) gem_pxp (reject-modify-context-protection-on)
[432.340159] Starting subtest: reject-modify-context-protection-on
[432.342626] Subtest reject-modify-context-protection-on: SUCCESS (0.002s)
[432.444489] [080/133] (588s left) kms_psr (fbc-pr-primary-page-flip)
[432.549312] Starting subtest: fbc-pr-primary-page-flip
[432.553312] Subtest fbc-pr-primary-page-flip: SKIP (0.000s)
[432.621131] [081/133] (588s left) perf_pmu (busy-idle)
[432.660515] Starting subtest: busy-idle
[432.663059] Starting dynamic subtest: rcs0
[433.343422] Dynamic subtest rcs0: SUCCESS (0.684s)
[433.355629] Starting dynamic subtest: bcs0
[434.002932] Dynamic subtest bcs0: SUCCESS (0.657s)
[434.014698] Starting dynamic subtest: vcs0
[435.926041] Dynamic subtest vcs0: SUCCESS (1.919s)
[435.938104] Starting dynamic subtest: vcs1
[437.911374] Dynamic subtest vcs1: SUCCESS (1.982s)
[437.922981] Starting dynamic subtest: vecs0
[439.892947] Dynamic subtest vecs0: SUCCESS (1.979s)
[439.904713] Starting dynamic subtest: ccs0
[440.557815] Dynamic subtest ccs0: SUCCESS (0.663s)
[440.560657] Subtest busy-idle: SUCCESS (7.900s)
[440.653317] [082/133] (580s left) gem_exec_params (no-blt)
[440.717676] Starting subtest: no-blt
[440.722990] Subtest no-blt: SUCCESS (0.005s)
[440.842962] [083/133] (580s left) gem_ctx_shared (exec-single-timeline)
[440.902763] Starting subtest: exec-single-timeline
[440.905229] Starting dynamic subtest: rcs0
[441.974648] Dynamic subtest rcs0: SUCCESS (1.069s)
[441.986801] Starting dynamic subtest: bcs0
[443.058905] Dynamic subtest bcs0: SUCCESS (1.082s)
[443.071637] Starting dynamic subtest: vcs0
[444.147615] Dynamic subtest vcs0: SUCCESS (1.085s)
[444.159593] Starting dynamic subtest: vcs1
[445.236619] Dynamic subtest vcs1: SUCCESS (1.085s)
[445.249084] Starting dynamic subtest: vecs0
[446.323672] Dynamic subtest vecs0: SUCCESS (1.084s)
[446.335780] Starting dynamic subtest: ccs0
[447.411636] Dynamic subtest ccs0: SUCCESS (1.084s)
[447.414695] Subtest exec-single-timeline: SUCCESS (6.509s)
[447.634179] [084/133] (573s left) intel_hwmon (hwmon-write)
[447.708258] Subtest hwmon-write: SKIP (0.000s)
[447.768844] [085/133] (573s left) gem_exec_balancer (parallel-out-fence)
[448.042550] Starting subtest: parallel-out-fence
[449.270050] Subtest parallel-out-fence: SUCCESS (1.226s)
[449.377109] [086/133] (572s left) i915_drm_fdinfo (memory-info-purgeable)
[449.414360] Starting subtest: memory-info-purgeable
[449.416791] Starting dynamic subtest: smem0
[451.967883] Dynamic subtest smem0: SUCCESS (2.557s)
[451.969978] Subtest memory-info-purgeable: SUCCESS (2.558s)
[452.046289] [087/133] (569s left) gem_basic (multigpu-create-close)
[452.097109] Starting subtest: multigpu-create-close
[452.100831] Subtest multigpu-create-close: SKIP (0.000s)
[452.191021] [088/133] (569s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-render)
[452.295306] Starting subtest: psr-2p-primscrn-pri-indfb-draw-render
[452.298866] Subtest psr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[452.394647] [089/133] (569s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[452.723499] Starting subtest: cursor-rapid-movement-max-size
[452.728948] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[452.857269] [090/133] (568s left) perf_pmu (all-busy-check-all)
[452.895870] Starting subtest: all-busy-check-all
[454.934255] Subtest all-busy-check-all: SUCCESS (2.036s)
[455.043335] [091/133] (566s left) gem_mmap_gtt (basic-small-bo-tiledy)
[455.090410] Subtest basic-small-bo-tiledY: SKIP (0.000s)
[455.148445] [092/133] (566s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-blt)
[455.245401] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-blt
[458.906554] Subtest fbc-1p-primscrn-cur-indfb-draw-blt: SUCCESS (3.666s)
[460.028287] [093/133] (561s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-render)
[460.131162] Starting subtest: psr-2p-primscrn-cur-indfb-draw-render
[460.135183] Subtest psr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[460.233647] [094/133] (561s left) kms_properties (connector-properties-atomic)
[460.334344] Starting subtest: connector-properties-atomic
[460.336277] Starting dynamic subtest: pipe-A-eDP-1
[468.313829] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.980s)
[468.327710] Starting dynamic subtest: pipe-None-DP-1
[468.332309] Dynamic subtest pipe-None-DP-1: SUCCESS (0.001s)
[468.343946] Starting dynamic subtest: pipe-None-HDMI-A-1
[468.347955] Dynamic subtest pipe-None-HDMI-A-1: SUCCESS (0.001s)
[468.356055] Starting dynamic subtest: pipe-None-DP-2
[468.360087] Dynamic subtest pipe-None-DP-2: SUCCESS (0.001s)
[468.368238] Starting dynamic subtest: pipe-None-HDMI-A-2
[468.372608] Dynamic subtest pipe-None-HDMI-A-2: SUCCESS (0.001s)
[468.380832] Starting dynamic subtest: pipe-None-DP-3
[468.385147] Dynamic subtest pipe-None-DP-3: SUCCESS (0.001s)
[468.393499] Starting dynamic subtest: pipe-None-DP-4
[468.397729] Dynamic subtest pipe-None-DP-4: SUCCESS (0.001s)
[468.399830] Subtest connector-properties-atomic: SUCCESS (7.990s)
[469.181324] [095/133] (552s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc)
[469.281199] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc
[469.283613] Starting dynamic subtest: pipe-A-eDP-1
[469.306749] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[469.326134] Starting dynamic subtest: pipe-B-eDP-1
[469.348227] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[469.366876] Starting dynamic subtest: pipe-C-eDP-1
[469.387762] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[469.399036] Starting dynamic subtest: pipe-D-eDP-1
[469.420971] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[469.425277] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc: SKIP (0.006s)
[471.555670] [096/133] (550s left) kms_color (ctm-negative)
[471.652514] Starting subtest: ctm-negative
[471.654730] Starting dynamic subtest: pipe-A-eDP-1
[472.344394] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.693s)
[472.353962] Starting dynamic subtest: pipe-B-eDP-1
[473.774085] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.427s)
[473.784196] Starting dynamic subtest: pipe-C-eDP-1
[475.180596] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.406s)
[475.190812] Starting dynamic subtest: pipe-D-eDP-1
[476.575906] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.395s)
[476.578224] Subtest ctm-negative: SUCCESS (4.926s)
[477.391445] [097/133] (544s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-upscaling)
[477.481208] Starting subtest: flip-32bpp-linear-to-64bpp-linear-upscaling
[477.483462] Starting dynamic subtest: pipe-A-default-mode
[480.227965] Dynamic subtest pipe-A-default-mode: SUCCESS (2.748s)
[480.230152] Subtest flip-32bpp-linear-to-64bpp-linear-upscaling: SUCCESS (2.750s)
[481.064662] [098/133] (540s left) syncobj_eventfd (binary-wait)
[481.121258] Starting subtest: binary-wait
[481.129892] Subtest binary-wait: SUCCESS (0.010s)
[481.205156] [099/133] (540s left) kms_big_fb (y-tiled-16bpp-rotate-0)
[481.310966] Starting subtest: y-tiled-16bpp-rotate-0
[481.315144] Subtest y-tiled-16bpp-rotate-0: SKIP (0.000s)
[481.418489] [100/133] (540s left) i915_pm_rpm (system-suspend-devices)
[483.044067] Starting subtest: system-suspend-devices
[490.811586] Subtest system-suspend-devices: SUCCESS (7.771s)
[491.195831] [101/133] (530s left) gem_userptr_blits (process-exit)
[491.281153] Starting subtest: process-exit
[491.285672] Subtest process-exit: SUCCESS (0.008s)
[491.422565] [102/133] (530s left) kms_chamelium_edid (hdmi-edid-change-during-suspend)
[491.803901] Subtest hdmi-edid-change-during-suspend: SKIP (0.000s)
[492.592150] [103/133] (529s left) kms_rotation_crc (multiplane-rotation)
[492.694861] Starting subtest: multiplane-rotation
[510.119113] Subtest multiplane-rotation: SUCCESS (17.426s)
[510.231176] [104/133] (511s left) kms_psr2_sf (psr2-overlay-plane-update-sf-dmg-area)
[510.327975] Starting subtest: psr2-overlay-plane-update-sf-dmg-area
[510.329968] Starting dynamic subtest: pipe-A-eDP-1
[516.217862] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.892s)
[516.228560] Starting dynamic subtest: pipe-B-eDP-1
[522.835683] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.617s)
[522.837974] Subtest psr2-overlay-plane-update-sf-dmg-area: SUCCESS (12.511s)
[523.721477] [105/133] (498s left) gem_exec_schedule (smoketest)
[523.910074] Starting subtest: smoketest
[523.912408] Starting dynamic subtest: rcs0
[529.442554] Dynamic subtest rcs0: SUCCESS (5.481s)
[529.455246] Starting dynamic subtest: bcs0
[534.977030] Dynamic subtest bcs0: SUCCESS (5.538s)
[534.987761] Starting dynamic subtest: vcs0
[540.474647] Dynamic subtest vcs0: SUCCESS (5.492s)
[540.488440] Starting dynamic subtest: vcs1
[545.971481] Dynamic subtest vcs1: SUCCESS (5.494s)
[545.985288] Starting dynamic subtest: vecs0
[551.469213] Dynamic subtest vecs0: SUCCESS (5.496s)
[551.480653] Starting dynamic subtest: ccs0
[556.984785] Dynamic subtest ccs0: SUCCESS (5.518s)
[556.986752] Subtest smoketest: SUCCESS (33.027s)
[557.122975] [106/133] (465s left) gem_ctx_param (invalid-get-ringsize)
[557.175790] Starting subtest: invalid-get-ringsize
[557.178154] Subtest invalid-get-ringsize: SUCCESS (0.000s)
[557.262294] [107/133] (464s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc)
[557.363890] Starting subtest: ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc
[557.366095] Starting dynamic subtest: pipe-A-eDP-1
[557.388833] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[557.400339] Starting dynamic subtest: pipe-B-eDP-1
[557.423151] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[557.434755] Starting dynamic subtest: pipe-C-eDP-1
[557.456257] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[557.467780] Starting dynamic subtest: pipe-D-eDP-1
[557.489848] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[557.493891] Subtest ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[559.659088] [108/133] (462s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[559.773266] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[560.386069] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (0.614s)
[560.550417] [109/133] (461s left) kms_psr (pr-primary-render)
[560.641758] Starting subtest: pr-primary-render
[560.646084] Subtest pr-primary-render: SKIP (0.000s)
[560.712673] [110/133] (461s left) kms_prop_blob (invalid-set-prop-any)
[560.775062] Starting subtest: invalid-set-prop-any
[560.777120] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[560.886896] [111/133] (461s left) kms_async_flips (test-time-stamp-atomic)
[560.978852] Starting subtest: test-time-stamp-atomic
[561.355911] Starting dynamic subtest: pipe-A-eDP-1
[562.172048] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.814s)
[562.564664] Starting dynamic subtest: pipe-B-eDP-1
[563.309518] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.752s)
[563.683694] Starting dynamic subtest: pipe-C-eDP-1
[564.434452] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.757s)
[564.798817] Starting dynamic subtest: pipe-D-eDP-1
[565.567627] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.774s)
[565.570049] Subtest test-time-stamp-atomic: SUCCESS (4.589s)
[566.731036] [112/133] (455s left) kms_flip (basic-flip-vs-dpms)
[566.834784] Starting subtest: basic-flip-vs-dpms
[566.837099] Starting dynamic subtest: A-eDP1
[569.427855] Dynamic subtest A-eDP1: SUCCESS (2.592s)
[569.445389] Starting dynamic subtest: B-eDP1
[571.595339] Dynamic subtest B-eDP1: SUCCESS (2.168s)
[571.608184] Starting dynamic subtest: C-eDP1
[573.669442] Dynamic subtest C-eDP1: SUCCESS (2.069s)
[573.679055] Starting dynamic subtest: D-eDP1
[575.748616] Dynamic subtest D-eDP1: SUCCESS (2.079s)
[575.753562] Subtest basic-flip-vs-dpms: SUCCESS (8.921s)
[576.549145] [113/133] (445s left) kms_pm_backlight (brightness-with-dpms)
[576.639393] Starting subtest: brightness-with-dpms
[576.688059] Starting dynamic subtest: eDP-1
[579.050840] Dynamic subtest eDP-1: SUCCESS (2.362s)
[579.053305] Subtest brightness-with-dpms: SUCCESS (2.414s)
[579.921204] [114/133] (442s left) kms_big_fb (x-tiled-8bpp-rotate-0)
[580.014612] Starting subtest: x-tiled-8bpp-rotate-0
[581.217351] Subtest x-tiled-8bpp-rotate-0: SUCCESS (1.203s)
[581.342221] [115/133] (441s left) gem_ctx_param (invalid-ctx-set)
[581.393570] Starting subtest: invalid-ctx-set
[581.395650] Subtest invalid-ctx-set: SUCCESS (0.001s)
[581.489231] [116/133] (441s left) kms_content_protection (dp-mst-type-1)
[581.631690] Starting subtest: dp-mst-type-1
[581.635535] Subtest dp-mst-type-1: SKIP (0.000s)
[581.763240] [117/133] (440s left) gem_mmap_gtt (cpuset-big-copy)
[581.814996] Subtest cpuset-big-copy: SKIP (0.000s)
[581.874196] [118/133] (440s left) gem_mmap_offset (close-race)
[581.929740] Starting subtest: close-race
[602.043996] Subtest close-race: SUCCESS (20.114s)
[602.177857] [119/133] (420s left) kms_chamelium_hpd (dp-hpd-fast)
[602.567848] Subtest dp-hpd-fast: SKIP (0.000s)
[603.344798] [120/133] (419s left) kms_invalid_mode (bad-vtotal)
[603.441529] Starting subtest: bad-vtotal
[603.443853] Starting dynamic subtest: pipe-A-eDP-1
[603.724122] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.284s)
[603.734827] Starting dynamic subtest: pipe-B-eDP-1
[603.737045] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[603.746557] Starting dynamic subtest: pipe-C-eDP-1
[603.748876] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[603.758053] Starting dynamic subtest: pipe-D-eDP-1
[603.760381] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[603.763027] Subtest bad-vtotal: SUCCESS (0.289s)
[604.593962] [121/133] (418s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-pwrite)
[604.692144] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-pwrite
[604.696423] Subtest psr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[604.779474] [122/133] (418s left) kms_plane_lowres (tiling-yf)
[604.874289] Starting subtest: tiling-yf
[604.878764] Subtest tiling-yf: SKIP (0.000s)
[604.949873] [123/133] (417s left) gem_exec_flush (basic-wb-ro-default)
[605.017361] Starting subtest: basic-wb-ro-default
[610.469470] Subtest basic-wb-ro-default: SUCCESS (5.452s)
[610.669856] [124/133] (412s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[610.778339] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[610.782555] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[610.875338] [125/133] (412s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-downscaling)
[610.965232] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-downscaling
[610.967438] Starting dynamic subtest: pipe-A-default-mode
[612.150722] Dynamic subtest pipe-A-default-mode: SKIP (1.184s)
[612.154783] Subtest flip-64bpp-4tile-to-16bpp-4tile-downscaling: SKIP (1.185s)
[612.320649] [126/133] (410s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-fullscreen)
[612.430690] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-fullscreen
[619.072900] Subtest fbcpsr-1p-primscrn-spr-indfb-fullscreen: SUCCESS (6.646s)
[619.198632] [127/133] (403s left) gem_exec_store (pages)
[619.260605] Starting subtest: pages
[619.262618] Starting dynamic subtest: rcs0
[619.359885] Dynamic subtest rcs0: SUCCESS (0.102s)
[619.372563] Starting dynamic subtest: bcs0
[619.440391] Dynamic subtest bcs0: SUCCESS (0.078s)
[619.452752] Starting dynamic subtest: vcs0
[619.507034] Dynamic subtest vcs0: SUCCESS (0.067s)
[619.518583] Starting dynamic subtest: vcs1
[619.574083] Dynamic subtest vcs1: SUCCESS (0.066s)
[619.583252] Starting dynamic subtest: vecs0
[619.643099] Dynamic subtest vecs0: SUCCESS (0.068s)
[619.652465] Starting dynamic subtest: ccs0
[619.709678] Dynamic subtest ccs0: SUCCESS (0.065s)
[619.712313] Subtest pages: SUCCESS (0.454s)
[619.899237] [128/133] (403s left) gem_exec_create (basic)
[619.972795] Starting subtest: basic
[619.975211] Starting dynamic subtest: smem
[623.894385] Dynamic subtest smem: SUCCESS (3.920s)
[623.897233] Subtest basic: SUCCESS (3.922s)
[624.094956] [129/133] (399s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0)
[624.194136] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0
[624.198414] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s)
[624.303815] [130/133] (398s left) gem_exec_balancer (bonded-dual)
[624.363874] Starting subtest: bonded-dual
[624.368445] Subtest bonded-dual: SKIP (0.000s)
[624.677515] [131/133] (398s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-render)
[624.767864] Starting subtest: psr-1p-primscrn-cur-indfb-draw-render
[631.493430] Subtest psr-1p-primscrn-cur-indfb-draw-render: SUCCESS (6.729s)
[631.609462] [132/133] (391s left) kms_cursor_crc (cursor-offscreen-32x10)
[631.758978] Starting subtest: cursor-offscreen-32x10
[631.805715] Subtest cursor-offscreen-32x10: SKIP (0.040s)
[632.059625] [133/133] (391s left) gem_exec_reloc (basic-wc)
[632.121603] Subtest basic-wc: SKIP (0.000s)
[632.197255] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: kms_flip
results: parsing output: 2/ for test: kms_cursor_legacy
results: parsing output: 3/ for test: kms_big_fb
results: parsing output: 4/ for test: kms_flip
results: parsing output: 5/ for test: gem_ctx_persistence
results: parsing output: 6/ for test: core_auth
results: parsing output: 7/ for test: vgem_basic
results: parsing output: 8/ for test: gem_ctx_bad_destroy
results: parsing output: 9/ for test: syncobj_wait
results: parsing output: 10/ for test: kms_flip
results: parsing output: 11/ for test: gem_render_copy
results: parsing output: 12/ for test: gem_mmap_gtt
results: parsing output: 13/ for test: gem_fenced_exec_thrash
results: parsing output: 14/ for test: gem_spin_batch
results: parsing output: 15/ for test: kms_plane
results: parsing output: 16/ for test: kms_vblank
results: parsing output: 17/ for test: syncobj_timeline
results: parsing output: 18/ for test: syncobj_timeline
results: parsing output: 19/ for test: kms_vblank
results: parsing output: 20/ for test: perf_pmu
results: parsing output: 21/ for test: kms_addfb_basic
results: parsing output: 22/ for test: gen9_exec_parse
results: parsing output: 23/ for test: gem_eio
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_ccs
results: parsing output: 27/ for test: kms_flip
results: parsing output: 28/ for test: kms_pipe_crc_basic
results: parsing output: 29/ for test: dumb_buffer
results: parsing output: 30/ for test: kms_psr
results: parsing output: 31/ for test: kms_flip
results: parsing output: 32/ for test: gem_exec_schedule
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: kms_cursor_edge_walk
results: parsing output: 35/ for test: gem_mmap_wc
results: parsing output: 36/ for test: api_intel_allocator
results: parsing output: 37/ for test: kms_psr2_sf
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: kms_plane_scaling
results: parsing output: 42/ for test: gem_gpgpu_fill
results: parsing output: 43/ for test: kms_atomic_interruptible
results: parsing output: 44/ for test: gem_exec_fence
results: parsing output: 45/ for test: kms_rotation_crc
results: parsing output: 46/ for test: gem_close_race
results: parsing output: 47/ for test: kms_draw_crc
results: parsing output: 48/ for test: gem_exec_schedule
results: parsing output: 49/ for test: gem_ctx_param
results: parsing output: 50/ for test: kms_pm_rpm
results: parsing output: 51/ for test: i915_drm_fdinfo
results: parsing output: 52/ for test: gem_userptr_blits
results: parsing output: 53/ for test: kms_ccs
results: parsing output: 54/ for test: gem_eio
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: kms_big_fb
results: parsing output: 57/ for test: perf
results: parsing output: 58/ for test: gem_tiled_blits
results: parsing output: 59/ for test: syncobj_timeline
results: parsing output: 60/ for test: perf_pmu
results: parsing output: 61/ for test: kms_lease
results: parsing output: 62/ for test: kms_ccs
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: kms_plane
results: parsing output: 65/ for test: kms_atomic_transition
results: parsing output: 66/ for test: gem_tiled_fence_blits
results: parsing output: 67/ for test: syncobj_timeline
results: parsing output: 68/ for test: i915_query
results: parsing output: 69/ for test: kms_cursor_legacy
results: parsing output: 70/ for test: kms_busy
results: parsing output: 71/ for test: gem_tiling_max_stride
results: parsing output: 72/ for test: kms_flip
results: parsing output: 73/ for test: vgem_basic
results: parsing output: 74/ for test: kms_psr
results: parsing output: 75/ for test: gem_mmap_gtt
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: kms_rotation_crc
results: parsing output: 78/ for test: gem_pxp
results: parsing output: 79/ for test: kms_psr
results: parsing output: 80/ for test: perf_pmu
results: parsing output: 81/ for test: gem_exec_params
results: parsing output: 82/ for test: gem_ctx_shared
results: parsing output: 83/ for test: intel_hwmon
results: parsing output: 84/ for test: gem_exec_balancer
results: parsing output: 85/ for test: i915_drm_fdinfo
results: parsing output: 86/ for test: gem_basic
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: kms_cursor_crc
results: parsing output: 89/ for test: perf_pmu
results: parsing output: 90/ for test: gem_mmap_gtt
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: kms_properties
results: parsing output: 94/ for test: kms_ccs
results: parsing output: 95/ for test: kms_color
results: parsing output: 96/ for test: kms_flip_scaled_crc
results: parsing output: 97/ for test: syncobj_eventfd
results: parsing output: 98/ for test: kms_big_fb
results: parsing output: 99/ for test: i915_pm_rpm
results: parsing output: 100/ for test: gem_userptr_blits
results: parsing output: 101/ for test: kms_chamelium_edid
results: parsing output: 102/ for test: kms_rotation_crc
results: parsing output: 103/ for test: kms_psr2_sf
results: parsing output: 104/ for test: gem_exec_schedule
results: parsing output: 105/ for test: gem_ctx_param
results: parsing output: 106/ for test: kms_ccs
results: parsing output: 107/ for test: kms_big_fb
results: parsing output: 108/ for test: kms_psr
results: parsing output: 109/ for test: kms_prop_blob
results: parsing output: 110/ for test: kms_async_flips
results: parsing output: 111/ for test: kms_flip
results: parsing output: 112/ for test: kms_pm_backlight
results: parsing output: 113/ for test: kms_big_fb
results: parsing output: 114/ for test: gem_ctx_param
results: parsing output: 115/ for test: kms_content_protection
results: parsing output: 116/ for test: gem_mmap_gtt
results: parsing output: 117/ for test: gem_mmap_offset
results: parsing output: 118/ for test: kms_chamelium_hpd
results: parsing output: 119/ for test: kms_invalid_mode
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: kms_plane_lowres
results: parsing output: 122/ for test: gem_exec_flush
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: kms_flip_scaled_crc
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: gem_exec_store
results: parsing output: 127/ for test: gem_exec_create
results: parsing output: 128/ for test: kms_big_fb
results: parsing output: 129/ for test: gem_exec_balancer
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: kms_cursor_crc
results: parsing output: 132/ for test: gem_exec_reloc
Done.