[57.212252] Initializing watchdogs
[57.212297]   /dev/watchdog0
[57.226685] [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.230976] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.233462] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.236717] [001/131] (960s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[57.361537] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[57.366121] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.002s)
[57.473707] [002/131] (960s left) gem_exec_fence (submit3)
[57.653859] Starting subtest: submit3
[57.658530] Subtest submit3: SKIP (0.000s)
[59.020579] [FACT gem_exec_fence (submit3)] new: kernel.kmod_is_loaded.vgem: true
[59.023743] [003/131] (958s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[59.121768] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt
[59.126151] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[59.258960] [004/131] (958s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-wc)
[59.355390] Starting subtest: fbc-rgb101010-draw-mmap-wc
[63.908199] Subtest fbc-rgb101010-draw-mmap-wc: SUCCESS (4.552s)
[65.047177] [005/131] (952s left) gem_exec_balancer (noheartbeat)
[65.301522] Starting subtest: noheartbeat
[65.306079] Subtest noheartbeat: SKIP (0.000s)
[65.455756] [006/131] (952s left) kms_chamelium_color (ctm-blue-to-red)
[65.555570] Subtest ctm-blue-to-red: SKIP (0.000s)
[65.594823] [007/131] (952s left) gem_workarounds (suspend-resume)
[65.665743] Starting subtest: suspend-resume
[82.021174] Subtest suspend-resume: SUCCESS (5.487s)
[82.188888] [008/131] (935s left) kms_plane_scaling (planes-downscale-factor-0-75-unity-scaling)
[82.307957] Starting subtest: planes-downscale-factor-0-75-unity-scaling
[82.310177] Starting dynamic subtest: pipe-A
[82.373848] Dynamic subtest pipe-A: SUCCESS (0.065s)
[82.384238] Starting dynamic subtest: pipe-B
[83.521196] Dynamic subtest pipe-B: SUCCESS (1.138s)
[83.531451] Starting dynamic subtest: pipe-C
[84.646536] Dynamic subtest pipe-C: SUCCESS (1.117s)
[84.657255] Starting dynamic subtest: pipe-D
[85.786454] Dynamic subtest pipe-D: SUCCESS (1.133s)
[85.788911] Subtest planes-downscale-factor-0-75-unity-scaling: SUCCESS (3.455s)
[86.971981] [009/131] (931s left) gem_pxp (create-regular-buffer)
[87.088511] Starting subtest: create-regular-buffer
[87.091038] Subtest create-regular-buffer: SUCCESS (0.000s)
[87.222305] [010/131] (930s left) gem_exec_params (rel-constants-invalid-rel-gen5)
[87.285836] Starting subtest: rel-constants-invalid-rel-gen5
[87.288028] Subtest rel-constants-invalid-rel-gen5: SUCCESS (0.000s)
[87.418866] [011/131] (930s left) gem_exec_nop (basic-series)
[87.488473] Starting subtest: basic-series
[103.772860] Subtest basic-series: SUCCESS (16.237s)
[104.018338] [012/131] (914s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-wc)
[104.126944] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-wc
[104.131059] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[104.269848] [013/131] (913s left) kms_psr (fbc-psr-primary-page-flip)
[104.367207] Starting subtest: fbc-psr-primary-page-flip
[104.369554] Starting dynamic subtest: eDP-1
[104.373829] Dynamic subtest eDP-1: SKIP (0.000s)
[104.378040] Subtest fbc-psr-primary-page-flip: SKIP (0.000s)
[104.462469] [014/131] (913s left) i915_pm_rps (engine-order)
[104.537110] Starting subtest: engine-order
[109.390647] Subtest engine-order: SUCCESS (4.847s)
[109.545911] [015/131] (908s left) gem_softpin (evict-active)
[109.596600] Starting subtest: evict-active
[109.606642] Subtest evict-active: SUCCESS (0.010s)
[109.746971] [016/131] (908s left) syncobj_timeline (wait-delayed-signal)
[109.809572] Starting subtest: wait-delayed-signal
[109.912799] Subtest wait-delayed-signal: SUCCESS (0.103s)
[110.023751] [017/131] (908s left) kms_panel_fitting (atomic-fastset)
[110.110861] Starting subtest: atomic-fastset
[110.113042] Starting dynamic subtest: pipe-A-eDP-1
[110.254059] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.143s)
[110.265647] Starting dynamic subtest: pipe-B-eDP-1
[111.450768] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.194s)
[111.462069] Starting dynamic subtest: pipe-C-eDP-1
[112.626615] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.174s)
[112.637035] Starting dynamic subtest: pipe-D-eDP-1
[113.763653] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.137s)
[113.765964] Subtest atomic-fastset: SUCCESS (3.651s)
[115.033243] [018/131] (903s left) kms_big_fb (x-tiled-16bpp-rotate-270)
[115.142171] Starting subtest: x-tiled-16bpp-rotate-270
[115.161530] Subtest x-tiled-16bpp-rotate-270: SKIP (0.019s)
[115.310990] [019/131] (903s left) api_intel_bb (render-ccs)
[115.359440] Starting subtest: render-ccs
[115.706661] Subtest render-ccs: SUCCESS (0.347s)
[115.860510] [020/131] (902s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc)
[115.948772] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc
[115.951170] Starting dynamic subtest: pipe-A-eDP-1
[115.973351] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[115.984855] Starting dynamic subtest: pipe-B-eDP-1
[116.004944] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[116.013069] Starting dynamic subtest: pipe-C-eDP-1
[116.033435] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[116.041028] Starting dynamic subtest: pipe-D-eDP-1
[116.061666] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[116.065855] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[118.292485] [021/131] (900s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted-signaled)
[118.356384] Starting subtest: multi-wait-all-for-submit-unsubmitted-signaled
[118.461003] Subtest multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.103s)
[118.560128] [022/131] (900s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-move)
[118.666384] Starting subtest: psr-2p-primscrn-cur-indfb-move
[118.670898] Subtest psr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[118.771934] [023/131] (899s left) gem_lmem_swapping (heavy-multi)
[118.814504] Subtest heavy-multi: SKIP (0.000s)
[118.854222] [024/131] (899s left) kms_async_flips (test-cursor)
[118.942511] Starting subtest: test-cursor
[118.946658] Subtest test-cursor: SKIP (0.000s)
[120.119248] [025/131] (898s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-sf)
[125.158637] Starting subtest: fbc-psr2-overlay-plane-move-continuous-exceed-sf
[125.160996] Starting dynamic subtest: pipe-A-eDP-1
[125.970186] Dynamic subtest pipe-A-eDP-1: SKIP (0.808s)
[125.979497] Starting dynamic subtest: pipe-B-eDP-1
[127.104127] Dynamic subtest pipe-B-eDP-1: SKIP (1.132s)
[127.108656] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-sf: SKIP (1.944s)
[128.340856] [026/131] (890s left) kms_plane_multiple (2x-tiling-4)
[128.453670] Starting subtest: 2x-tiling-4
[128.458136] Subtest 2x-tiling-4: SKIP (0.000s)
[128.550662] [027/131] (890s left) gem_ctx_param (invalid-param-set)
[128.611454] Starting subtest: invalid-param-set
[128.613843] Subtest invalid-param-set: SUCCESS (0.000s)
[128.713425] [028/131] (890s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[128.812543] Starting subtest: bad-pixel-format-y-tiled-ccs
[128.814844] Starting dynamic subtest: pipe-A-eDP-1
[128.839051] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[128.849779] Starting dynamic subtest: pipe-B-eDP-1
[128.870888] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[128.878684] Starting dynamic subtest: pipe-C-eDP-1
[128.900018] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[128.907740] Starting dynamic subtest: pipe-D-eDP-1
[128.929306] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[128.933674] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.003s)
[131.092121] [029/131] (887s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[131.187226] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[131.189513] Starting dynamic subtest: pipe-A
[131.206908] Dynamic subtest pipe-A: SKIP (0.016s)
[131.218727] Starting dynamic subtest: pipe-B
[131.237053] Dynamic subtest pipe-B: SKIP (0.029s)
[131.247348] Starting dynamic subtest: pipe-C
[131.264013] Dynamic subtest pipe-C: SKIP (0.028s)
[131.272063] Starting dynamic subtest: pipe-D
[131.291707] Dynamic subtest pipe-D: SKIP (0.028s)
[131.295530] Subtest plane-downscale-factor-0-5-with-modifiers: SKIP (0.102s)
[131.423283] [030/131] (887s left) syncobj_timeline (multi-wait-all-available-submitted-signaled)
[131.488845] Starting subtest: multi-wait-all-available-submitted-signaled
[131.491205] Subtest multi-wait-all-available-submitted-signaled: SUCCESS (0.000s)
[131.625985] [031/131] (887s left) perf (missing-sample-flags)
[131.756877] Starting subtest: missing-sample-flags
[131.758727] Subtest missing-sample-flags: SUCCESS (0.000s)
[131.799556] [032/131] (887s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-pwrite)
[131.899957] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-pwrite
[137.937549] Subtest fbc-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (6.040s)
[139.097532] [033/131] (879s left) kms_pm_rpm (system-suspend-idle)
[140.948471] Starting subtest: system-suspend-idle
[160.243079] Subtest system-suspend-idle: SUCCESS (4.307s)
[160.639825] [034/131] (858s left) kms_vblank (wait-forked)
[160.745373] Starting subtest: wait-forked
[160.748196] Starting dynamic subtest: pipe-A-eDP-1
[162.562492] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.805s)
[162.578772] Starting dynamic subtest: pipe-D-eDP-1
[165.033604] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.456s)
[165.036636] Subtest wait-forked: SUCCESS (4.264s)
[165.937735] [035/131] (853s left) kms_big_fb (y-tiled-32bpp-rotate-0)
[166.047544] Starting subtest: y-tiled-32bpp-rotate-0
[166.052702] Subtest y-tiled-32bpp-rotate-0: SKIP (0.000s)
[166.177993] [036/131] (853s left) kms_pipe_crc_basic (read-crc)
[166.269386] Starting subtest: read-crc
[166.271947] Starting dynamic subtest: pipe-A-eDP-1
[166.988482] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.714s)
[167.000050] Starting dynamic subtest: pipe-B-eDP-1
[168.391474] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.399s)
[168.400611] Starting dynamic subtest: pipe-C-eDP-1
[169.696433] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.296s)
[169.707366] Starting dynamic subtest: pipe-D-eDP-1
[171.054777] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.355s)
[171.057533] Subtest read-crc: SUCCESS (4.769s)
[171.908786] [037/131] (847s left) kms_cursor_legacy (single-move)
[172.033440] Starting subtest: single-move
[172.035994] Starting dynamic subtest: pipe-A
[177.425636] Dynamic subtest pipe-A: SUCCESS (5.380s)
[177.437580] Starting dynamic subtest: pipe-B
[182.814157] Dynamic subtest pipe-B: SUCCESS (5.380s)
[182.825546] Starting dynamic subtest: pipe-C
[188.200535] Dynamic subtest pipe-C: SUCCESS (5.380s)
[188.211964] Starting dynamic subtest: pipe-D
[193.586586] Dynamic subtest pipe-D: SUCCESS (5.380s)
[193.598328] Starting dynamic subtest: all-pipes
[198.973955] Dynamic subtest all-pipes: SUCCESS (5.388s)
[198.976880] Subtest single-move: SUCCESS (26.918s)
[199.141839] [038/131] (820s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-sf)
[204.193759] Starting subtest: pr-overlay-plane-move-continuous-exceed-sf
[204.199330] Subtest pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.002s)
[205.070847] [039/131] (814s left) gem_unref_active_buffers
[205.326886] [040/131] (814s left) kms_psr (fbc-psr2-primary-mmap-gtt)
[205.436347] Starting subtest: fbc-psr2-primary-mmap-gtt
[205.438501] Starting dynamic subtest: eDP-1
[205.443405] Dynamic subtest eDP-1: SKIP (0.000s)
[205.448420] Subtest fbc-psr2-primary-mmap-gtt: SKIP (0.001s)
[205.532460] [041/131] (813s left) syncobj_wait (invalid-signal-zero-handles)
[205.591186] Starting subtest: invalid-signal-zero-handles
[205.593453] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[205.735864] [042/131] (813s left) gen9_exec_parse (bb-oversize)
[205.796653] Subtest bb-oversize: SKIP (0.000s)
[205.865436] [043/131] (813s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted)
[205.923008] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted
[206.029115] Subtest etime-multi-wait-all-for-submit-available-unsubmitted: SUCCESS (0.107s)
[206.129817] [044/131] (813s left) gem_exec_reloc (basic-write-cpu-noreloc)
[206.196571] Subtest basic-write-cpu-noreloc: SKIP (0.000s)
[206.283797] [045/131] (813s left) gem_mmap_offset (bad-flags)
[206.341508] Starting subtest: bad-flags
[206.343860] Subtest bad-flags: SUCCESS (0.000s)
[206.480888] [046/131] (813s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[206.587941] Starting subtest: cursor-vs-flip-legacy
[235.819670] Subtest cursor-vs-flip-legacy: SUCCESS (29.226s)
[236.695846] [047/131] (782s left) kms_rotation_crc (sprite-rotation-90-pos-100-0)
[236.802770] Starting subtest: sprite-rotation-90-pos-100-0
[236.808472] Subtest sprite-rotation-90-pos-100-0: SKIP (0.001s)
[236.899915] [048/131] (782s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[237.009071] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip
[237.996625] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip: SUCCESS (0.989s)
[238.171153] [049/131] (781s left) gem_create (busy-create)
[238.242264] Starting subtest: busy-create
[238.244240] Starting dynamic subtest: smem0
[271.731115] Dynamic subtest smem0: SUCCESS (33.487s)
[271.734020] Subtest busy-create: SUCCESS (33.489s)
[271.942997] [050/131] (747s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-render)
[272.049923] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-render
[276.274085] Subtest fbc-1p-offscreen-pri-indfb-draw-render: SUCCESS (4.228s)
[277.517444] [051/131] (742s left) perf (invalid-open-flags)
[277.632167] Starting subtest: invalid-open-flags
[277.634469] Subtest invalid-open-flags: SUCCESS (0.000s)
[277.676589] [052/131] (742s left) perf (oa-formats)
[277.793748] Starting subtest: oa-formats
[277.796489] Starting dynamic subtest: 0-rcs0
[277.810013] Dynamic subtest 0-rcs0: SUCCESS (0.016s)
[277.819966] Starting dynamic subtest: 1-vcs1
[277.833401] Dynamic subtest 1-vcs1: SUCCESS (0.023s)
[277.836176] Subtest oa-formats: SUCCESS (0.040s)
[277.908859] [053/131] (742s left) kms_big_fb (4-tiled-8bpp-rotate-270)
[278.012091] Starting subtest: 4-tiled-8bpp-rotate-270
[278.038359] Subtest 4-tiled-8bpp-rotate-270: SKIP (0.025s)
[278.190401] [054/131] (741s left) kms_cursor_crc (cursor-onscreen-64x21)
[278.381707] Starting subtest: cursor-onscreen-64x21
[278.432296] Subtest cursor-onscreen-64x21: SKIP (0.048s)
[278.677453] [055/131] (741s left) kms_rotation_crc (primary-4-tiled-reflect-x-180)
[278.772171] Starting subtest: primary-4-tiled-reflect-x-180
[285.841605] Subtest primary-4-tiled-reflect-x-180: SUCCESS (7.071s)
[287.056753] [056/131] (733s left) kms_cursor_crc (cursor-onscreen-256x256)
[287.290854] Starting subtest: cursor-onscreen-256x256
[287.293572] Starting dynamic subtest: pipe-A-eDP-1
[289.390389] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.096s)
[289.418218] Starting dynamic subtest: pipe-D-eDP-1
[292.174536] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.764s)
[292.177368] Subtest cursor-onscreen-256x256: SUCCESS (4.885s)
[293.003249] [057/131] (727s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-msflip-blt)
[293.111974] Starting subtest: fbcpsr-1p-primscrn-shrfb-msflip-blt
[300.335178] Subtest fbcpsr-1p-primscrn-shrfb-msflip-blt: SUCCESS (7.227s)
[300.497266] [058/131] (719s left) kms_async_flips (async-flip-with-page-flip-events-tiled)
[300.601779] Starting subtest: async-flip-with-page-flip-events-tiled
[300.974073] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs
[303.775287] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: SUCCESS (2.800s)
[303.790798] Starting dynamic subtest: pipe-A-eDP-1-4
[305.961893] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.185s)
[305.972465] Starting dynamic subtest: pipe-A-eDP-1-4
[307.972182] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.009s)
[307.983994] Starting dynamic subtest: pipe-A-eDP-1-x
[310.151541] Dynamic subtest pipe-A-eDP-1-x: SUCCESS (2.178s)
[310.539013] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs
[313.302768] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: SUCCESS (2.769s)
[313.313051] Starting dynamic subtest: pipe-B-eDP-1-4
[315.477933] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.174s)
[315.487533] Starting dynamic subtest: pipe-B-eDP-1-4
[317.488001] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.008s)
[317.499461] Starting dynamic subtest: pipe-B-eDP-1-x
[319.678514] Dynamic subtest pipe-B-eDP-1-x: SUCCESS (2.190s)
[320.058000] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs
[322.836535] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.789s)
[322.849431] Starting dynamic subtest: pipe-C-eDP-1-4
[325.022806] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.185s)
[325.038501] Starting dynamic subtest: pipe-C-eDP-1-4
[327.033282] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.008s)
[327.045648] Starting dynamic subtest: pipe-C-eDP-1-x
[329.269002] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.234s)
[329.630574] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs
[332.362475] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.738s)
[332.374589] Starting dynamic subtest: pipe-D-eDP-1-4
[334.527607] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.164s)
[334.538710] Starting dynamic subtest: pipe-D-eDP-1-4
[336.537144] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.008s)
[336.547639] Starting dynamic subtest: pipe-D-eDP-1-x
[338.716630] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.178s)
[338.719596] Subtest async-flip-with-page-flip-events-tiled: SUCCESS (38.110s)
[339.978175] [059/131] (680s left) perf (polling)
[340.087175] Starting subtest: polling
[340.089932] Starting dynamic subtest: 0-rcs0
[350.098030] Dynamic subtest 0-rcs0: SUCCESS (10.006s)
[350.108487] Starting dynamic subtest: 1-vcs1
[360.123887] Dynamic subtest 1-vcs1: SUCCESS (10.022s)
[360.126849] Subtest polling: SUCCESS (20.031s)
[360.175424] [060/131] (660s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[360.272568] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[360.278382] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[360.407115] [061/131] (659s left) syncobj_timeline (reset-signaled)
[360.460816] Starting subtest: reset-signaled
[360.463370] Subtest reset-signaled: SUCCESS (0.000s)
[360.588942] [062/131] (659s left) kms_rmfb (rmfb-ioctl)
[360.673301] Starting subtest: rmfb-ioctl
[360.676026] Starting dynamic subtest: pipe-A-eDP-1
[360.776294] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.106s)
[360.787075] Starting dynamic subtest: pipe-B-eDP-1
[361.910441] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.133s)
[361.920416] Starting dynamic subtest: pipe-C-eDP-1
[363.033904] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.121s)
[363.049887] Starting dynamic subtest: pipe-D-eDP-1
[364.166024] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.131s)
[364.168476] Subtest rmfb-ioctl: SUCCESS (3.496s)
[365.409588] [063/131] (654s left) gem_userptr_blits (create-destroy-sync)
[365.492962] Starting subtest: create-destroy-sync
[370.504394] Subtest create-destroy-sync: SUCCESS (5.011s)
[370.666799] [064/131] (649s left) gem_exec_reloc (basic-gtt-cpu-active)
[370.722506] Subtest basic-gtt-cpu-active: SKIP (0.000s)
[370.793529] [065/131] (649s left) api_intel_allocator (execbuf-with-allocator)
[370.855444] Starting subtest: execbuf-with-allocator
[370.862184] Subtest execbuf-with-allocator: SUCCESS (0.009s)
[371.012731] [066/131] (649s left) kms_lease (cursor-implicit-plane)
[371.114367] Starting subtest: cursor-implicit-plane
[371.116759] Starting dynamic subtest: pipe-A-eDP-1
[371.582628] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.466s)
[371.592618] Starting dynamic subtest: pipe-B-eDP-1
[372.714046] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.127s)
[372.724213] Starting dynamic subtest: pipe-C-eDP-1
[373.819591] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.105s)
[373.830584] Starting dynamic subtest: pipe-D-eDP-1
[374.962145] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.140s)
[374.964974] Subtest cursor-implicit-plane: SUCCESS (3.846s)
[375.841598] [067/131] (644s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions-varying-size)
[375.968435] Starting subtest: cursorA-vs-flipB-atomic-transitions-varying-size
[375.973280] Subtest cursorA-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[376.139347] [068/131] (644s left) syncobj_wait (invalid-signal-illegal-handle)
[376.212452] Starting subtest: invalid-signal-illegal-handle
[376.215421] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[376.346586] [069/131] (644s left) kms_psr (psr-primary-blt)
[376.433344] Starting subtest: psr-primary-blt
[376.436002] Starting dynamic subtest: eDP-1
[378.223634] Dynamic subtest eDP-1: SUCCESS (1.789s)
[378.226597] Subtest psr-primary-blt: SUCCESS (1.790s)
[379.110108] [070/131] (641s left) kms_ccs (crc-sprite-planes-basic-y-tiled-gen12-rc-ccs)
[379.200766] Starting subtest: crc-sprite-planes-basic-y-tiled-gen12-rc-ccs
[379.203084] Starting dynamic subtest: pipe-A-eDP-1
[379.261717] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[379.270570] Starting dynamic subtest: pipe-B-eDP-1
[379.338616] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[379.348606] Starting dynamic subtest: pipe-C-eDP-1
[379.416178] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[379.426601] Starting dynamic subtest: pipe-D-eDP-1
[379.488056] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[379.492469] Subtest crc-sprite-planes-basic-y-tiled-gen12-rc-ccs: SKIP (0.009s)
[381.385922] [071/131] (639s left) kms_psr (pr-primary-blt)
[381.483576] Starting subtest: pr-primary-blt
[381.488384] Subtest pr-primary-blt: SKIP (0.000s)
[381.581303] [072/131] (639s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-move)
[381.689942] Starting subtest: psr-2p-primscrn-spr-indfb-move
[381.695163] Subtest psr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[381.821311] [073/131] (638s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[381.917575] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[381.919885] Starting dynamic subtest: pipe-A-default-mode
[383.133300] Dynamic subtest pipe-A-default-mode: SKIP (1.212s)
[383.138268] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SKIP (1.213s)
[383.320416] [074/131] (637s left) gem_exec_schedule (u-semaphore-noskip)
[383.408640] Starting subtest: u-semaphore-noskip
[383.806452] Subtest u-semaphore-noskip: SUCCESS (0.409s)
[384.202986] [075/131] (636s left) i915_drm_fdinfo (memory-info-idle)
[384.247500] Starting subtest: memory-info-idle
[384.250452] Starting dynamic subtest: smem0
[384.295488] Dynamic subtest smem0: SUCCESS (0.051s)
[384.298605] Subtest memory-info-idle: SUCCESS (0.052s)
[384.393399] [076/131] (636s left) i915_selftest (live)
[386.298610] Starting subtest: live
[386.301480] Starting dynamic subtest: sanitycheck
[390.514142] Dynamic subtest sanitycheck: SUCCESS (4.217s)
[390.524157] Starting dynamic subtest: uncore
[394.707048] Dynamic subtest uncore: SUCCESS (4.192s)
[394.716892] Starting dynamic subtest: workarounds
[414.150964] Dynamic subtest workarounds: FAIL (18.909s)
[414.160958] Starting dynamic subtest: gt_engines
[418.040444] Dynamic subtest gt_engines: SUCCESS (4.425s)
[418.050597] Starting dynamic subtest: gt_timelines
[435.086231] Dynamic subtest gt_timelines: SUCCESS (17.045s)
[435.096143] Starting dynamic subtest: gt_contexts
[440.790010] Dynamic subtest gt_contexts: SUCCESS (5.702s)
[440.800396] Starting dynamic subtest: gt_lrc
[448.343929] Dynamic subtest gt_lrc: SUCCESS (7.552s)
[448.359041] Starting dynamic subtest: gt_mocs
[452.537628] Dynamic subtest gt_mocs: SUCCESS (4.191s)
[452.546874] Starting dynamic subtest: gt_pm
[458.635013] Dynamic subtest gt_pm: SUCCESS (6.096s)
[458.645396] Starting dynamic subtest: gt_heartbeat
[462.828032] Dynamic subtest gt_heartbeat: SUCCESS (4.190s)
[462.843446] Starting dynamic subtest: gt_tlb
[473.755400] Dynamic subtest gt_tlb: SUCCESS (10.924s)
[473.765762] Starting dynamic subtest: requests
[486.748149] Dynamic subtest requests: SUCCESS (12.989s)
[486.758403] Starting dynamic subtest: migrate
[494.183025] Dynamic subtest migrate: SUCCESS (7.433s)
[494.193228] Starting dynamic subtest: active
[498.246126] Dynamic subtest active: SUCCESS (4.061s)
[498.261129] Starting dynamic subtest: objects
[502.940114] Dynamic subtest objects: SUCCESS (4.691s)
[502.950762] Starting dynamic subtest: mman
[509.192843] Dynamic subtest mman: SUCCESS (6.250s)
[509.208294] Starting dynamic subtest: dmabuf
[513.385845] Dynamic subtest dmabuf: SUCCESS (4.190s)
[513.400417] Starting dynamic subtest: vma
[519.189058] Dynamic subtest vma: SUCCESS (5.800s)
[519.199002] Starting dynamic subtest: coherency
[524.180030] Dynamic subtest coherency: SUCCESS (4.988s)
[524.190060] Starting dynamic subtest: gtt
[535.482090] Dynamic subtest gtt: SUCCESS (11.298s)
[535.492164] Starting dynamic subtest: gem
[542.657095] Dynamic subtest gem: SUCCESS (7.172s)
[542.667510] Starting dynamic subtest: evict
[549.631183] Dynamic subtest evict: SUCCESS (6.971s)
[549.641400] Starting dynamic subtest: hugepages
[561.875865] Dynamic subtest hugepages: SUCCESS (12.240s)
[561.886401] Starting dynamic subtest: gem_contexts
[590.433964] Dynamic subtest gem_contexts: SUCCESS (28.551s)
[590.448685] Starting dynamic subtest: client
[594.658061] Dynamic subtest client: SUCCESS (4.222s)
[594.668994] Starting dynamic subtest: gem_migrate
[598.836165] Dynamic subtest gem_migrate: SUCCESS (4.167s)
[598.846611] Starting dynamic subtest: reset
[603.131718] Dynamic subtest reset: SUCCESS (4.301s)
[603.142539] Starting dynamic subtest: memory_region
[607.260122] Dynamic subtest memory_region: SUCCESS (4.126s)
[607.274733] Starting dynamic subtest: hangcheck
[621.758300] Dynamic subtest hangcheck: SUCCESS (14.493s)
[621.767414] Starting dynamic subtest: execlists
[625.856399] Dynamic subtest execlists: SUCCESS (4.095s)
[625.864877] Starting dynamic subtest: ring_submission
[631.629640] Dynamic subtest ring_submission: SUCCESS (5.770s)
[631.639064] Starting dynamic subtest: perf
[635.912025] Dynamic subtest perf: SUCCESS (4.280s)
[635.922137] Starting dynamic subtest: slpc
[649.470941] Dynamic subtest slpc: SUCCESS (13.560s)
[649.481000] Starting dynamic subtest: guc
[654.891405] Dynamic subtest guc: SUCCESS (5.421s)
[654.901784] Starting dynamic subtest: guc_multi_lrc
[659.100283] Dynamic subtest guc_multi_lrc: SUCCESS (4.209s)
[659.110213] Starting dynamic subtest: guc_hang
[665.482215] Dynamic subtest guc_hang: SUCCESS (6.383s)
[665.492944] Starting dynamic subtest: late_gt_pm
[669.691204] Dynamic subtest late_gt_pm: SUCCESS (4.208s)
[669.694420] Subtest live: FAIL (283.380s)
[669.724785] [FACT i915_selftest (live)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[669.725464] [FACT i915_selftest (live)] deleted: kernel.kmod_is_loaded.i915: true
[669.729817] [077/131] (351s left) gem_userptr_blits (nohangcheck)
[672.512370] Starting subtest: nohangcheck
[672.794174] Subtest nohangcheck: SUCCESS (0.277s)
[672.905201] [FACT gem_userptr_blits (nohangcheck)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[672.906402] [FACT gem_userptr_blits (nohangcheck)] new: kernel.kmod_is_loaded.i915: true
[672.910386] [078/131] (348s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs)
[673.017639] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs
[673.020283] Starting dynamic subtest: pipe-A-eDP-1
[673.866254] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.842s)
[673.875067] Starting dynamic subtest: pipe-B-eDP-1
[675.488845] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.616s)
[675.504608] Starting dynamic subtest: pipe-C-eDP-1
[676.950745] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.462s)
[676.960715] Starting dynamic subtest: pipe-D-eDP-1
[678.415355] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.465s)
[678.418091] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs: SUCCESS (5.390s)
[679.652705] [079/131] (341s left) gem_softpin (allocator-nopin)
[679.725736] Starting subtest: allocator-nopin
[679.808688] Subtest allocator-nopin: SUCCESS (0.082s)
[679.965943] [080/131] (341s left) kms_draw_crc (draw-method-pwrite)
[680.060629] Starting subtest: draw-method-pwrite
[680.063145] Starting dynamic subtest: xrgb8888-untiled
[680.612621] Dynamic subtest xrgb8888-untiled: SUCCESS (0.555s)
[680.622225] Starting dynamic subtest: rgb565-untiled
[681.045593] Dynamic subtest rgb565-untiled: SUCCESS (0.431s)
[681.054309] Starting dynamic subtest: xrgb2101010-untiled
[681.556644] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.510s)
[681.566228] Starting dynamic subtest: xbgr16161616f-untiled
[682.280613] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.721s)
[682.290234] Starting dynamic subtest: xrgb8888-xtiled
[683.989608] Dynamic subtest xrgb8888-xtiled: SUCCESS (1.711s)
[683.998861] Starting dynamic subtest: rgb565-xtiled
[685.011437] Dynamic subtest rgb565-xtiled: SUCCESS (1.021s)
[685.021390] Starting dynamic subtest: xrgb2101010-xtiled
[686.688456] Dynamic subtest xrgb2101010-xtiled: SUCCESS (1.677s)
[686.698260] Starting dynamic subtest: xbgr16161616f-xtiled
[689.832353] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (3.143s)
[689.841630] Starting dynamic subtest: xrgb8888-4tiled
[690.876129] Dynamic subtest xrgb8888-4tiled: SUCCESS (1.044s)
[690.887183] Starting dynamic subtest: rgb565-4tiled
[691.420594] Dynamic subtest rgb565-4tiled: SUCCESS (0.544s)
[691.429586] Starting dynamic subtest: xrgb2101010-4tiled
[692.453567] Dynamic subtest xrgb2101010-4tiled: SUCCESS (1.033s)
[692.463599] Starting dynamic subtest: xbgr16161616f-4tiled
[695.297458] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (2.844s)
[695.300258] Subtest draw-method-pwrite: SUCCESS (15.245s)
[695.426675] [081/131] (325s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[695.531507] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip
[695.536604] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[695.659051] [082/131] (325s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-plflip-blt)
[695.761282] Starting subtest: fbc-2p-primscrn-indfb-plflip-blt
[695.766394] Subtest fbc-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[695.879007] [083/131] (325s left) syncobj_timeline (host-signal-points)
[695.935282] Starting subtest: host-signal-points
[695.938002] Subtest host-signal-points: SUCCESS (0.004s)
[696.073938] [084/131] (325s left) gem_mmap_gtt (basic-small-bo)
[696.138473] Subtest basic-small-bo: SKIP (0.000s)
[696.202978] [085/131] (325s left) perf_pmu (semaphore-wait-idle)
[696.243020] Starting subtest: semaphore-wait-idle
[696.244890] Starting dynamic subtest: rcs0
[696.756587] Dynamic subtest rcs0: SUCCESS (0.514s)
[696.768575] Starting dynamic subtest: bcs0
[697.268963] Dynamic subtest bcs0: SUCCESS (0.510s)
[697.281625] Starting dynamic subtest: vcs0
[697.781578] Dynamic subtest vcs0: SUCCESS (0.510s)
[697.794267] Starting dynamic subtest: vcs1
[698.294622] Dynamic subtest vcs1: SUCCESS (0.509s)
[698.305430] Starting dynamic subtest: vecs0
[698.808458] Dynamic subtest vecs0: SUCCESS (0.511s)
[698.820954] Starting dynamic subtest: ccs0
[699.325902] Dynamic subtest ccs0: SUCCESS (0.513s)
[699.328944] Subtest semaphore-wait-idle: SUCCESS (3.086s)
[700.790205] [086/131] (320s left) kms_addfb_basic (no-handle)
[700.852384] Starting subtest: no-handle
[700.855264] Subtest no-handle: SUCCESS (0.000s)
[701.029070] [087/131] (320s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-pwrite)
[701.132781] Starting subtest: fbcpsr-rgb101010-draw-pwrite
[708.031390] Subtest fbcpsr-rgb101010-draw-pwrite: SUCCESS (6.902s)
[708.222341] [088/131] (313s left) gem_render_copy (yf-tiled-ccs-to-linear)
[708.290510] Starting subtest: yf-tiled-ccs-to-linear
[708.296226] Subtest yf-tiled-ccs-to-linear: SKIP (0.000s)
[708.499541] [089/131] (313s left) kms_flip (wf_vblank-ts-check)
[708.595302] Starting subtest: wf_vblank-ts-check
[708.597602] Starting dynamic subtest: A-eDP1
[717.492531] Dynamic subtest A-eDP1: SUCCESS (8.900s)
[717.504614] Starting dynamic subtest: B-eDP1
[726.432491] Dynamic subtest B-eDP1: SUCCESS (8.940s)
[726.441618] Starting dynamic subtest: C-eDP1
[735.351893] Dynamic subtest C-eDP1: SUCCESS (8.917s)
[735.361616] Starting dynamic subtest: D-eDP1
[744.284248] Dynamic subtest D-eDP1: SUCCESS (8.930s)
[744.287190] Subtest wf_vblank-ts-check: SUCCESS (35.698s)
[745.522375] [090/131] (276s left) gem_exec_schedule (deep)
[745.710426] Starting subtest: deep
[745.713100] Starting dynamic subtest: rcs0
[750.539977] Dynamic subtest rcs0: SKIP (4.828s)
[750.556231] Starting dynamic subtest: vcs0
[761.059709] Dynamic subtest vcs0: SUCCESS (10.522s)
[761.078393] Starting dynamic subtest: vcs1
[772.014415] Dynamic subtest vcs1: SUCCESS (10.953s)
[772.030051] Starting dynamic subtest: vecs0
[783.106300] Dynamic subtest vecs0: SUCCESS (11.090s)
[783.116583] Starting dynamic subtest: ccs0
[795.280362] Dynamic subtest ccs0: SUCCESS (12.173s)
[795.284386] Subtest deep: SUCCESS (49.571s)
[796.424340] [091/131] (225s left) kms_sequence (queue-idle)
[796.522297] Starting subtest: queue-idle
[796.524348] Starting dynamic subtest: pipe-A-eDP-1
[798.331169] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.809s)
[798.341382] Starting dynamic subtest: pipe-B-eDP-1
[800.753344] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.421s)
[800.763608] Starting dynamic subtest: pipe-C-eDP-1
[803.196705] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.439s)
[803.206485] Starting dynamic subtest: pipe-D-eDP-1
[805.634583] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.438s)
[805.637495] Subtest queue-idle: SUCCESS (9.113s)
[806.457283] [092/131] (215s left) gem_gtt_cpu_tlb
[806.602958] [093/131] (215s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[806.713308] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc
[813.225376] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.515s)
[813.395395] [094/131] (208s left) gem_eio (create-ext)
[813.640436] Starting subtest: create-ext
[813.949289] Subtest create-ext: SUCCESS (0.309s)
[814.094004] [095/131] (207s left) gem_partial_pwrite_pread (writes-after-reads-uncached)
[814.158954] Subtest writes-after-reads-uncached: SKIP (0.000s)
[814.242395] [096/131] (207s left) sysfs_preempt_timeout (idempotent)
[814.303669] Starting subtest: idempotent
[814.306237] Starting dynamic subtest: vecs0
[814.308483] Dynamic subtest vecs0: SUCCESS (0.001s)
[814.318427] Starting dynamic subtest: ccs0
[814.320519] Dynamic subtest ccs0: SUCCESS (0.000s)
[814.329334] Starting dynamic subtest: vcs0
[814.331421] Dynamic subtest vcs0: SUCCESS (0.000s)
[814.341079] Starting dynamic subtest: bcs0
[814.343253] Dynamic subtest bcs0: SUCCESS (0.001s)
[814.352467] Starting dynamic subtest: rcs0
[814.354873] Dynamic subtest rcs0: SUCCESS (0.000s)
[814.363656] Starting dynamic subtest: vcs1
[814.365897] Dynamic subtest vcs1: SUCCESS (0.000s)
[814.367845] Subtest idempotent: SUCCESS (0.009s)
[814.435371] [097/131] (207s left) gem_busy (semaphore)
[814.623525] Subtest semaphore: SKIP (0.000s)
[814.895937] [098/131] (207s left) sriov_basic (enable-vfs-bind-unbind-each)
[814.961145] Starting subtest: enable-vfs-bind-unbind-each
[814.963733] Starting dynamic subtest: numvfs-1
[815.051510] Dynamic subtest numvfs-1: FAIL (0.005s)
[815.062193] Starting dynamic subtest: numvfs-2
[815.152594] Dynamic subtest numvfs-2: FAIL (0.003s)
[815.164741] Starting dynamic subtest: numvfs-3
[815.250602] Dynamic subtest numvfs-3: FAIL (0.003s)
[815.260956] Starting dynamic subtest: numvfs-4
[815.345477] Dynamic subtest numvfs-4: FAIL (0.003s)
[815.356214] Starting dynamic subtest: numvfs-5
[815.441364] Dynamic subtest numvfs-5: FAIL (0.003s)
[815.452572] Starting dynamic subtest: numvfs-6
[815.541182] Dynamic subtest numvfs-6: FAIL (0.003s)
[815.551154] Starting dynamic subtest: numvfs-7
[815.639086] Dynamic subtest numvfs-7: FAIL (0.003s)
[815.649252] Starting dynamic subtest: numvfs-random
[815.735932] Dynamic subtest numvfs-random: FAIL (0.003s)
[815.738290] Subtest enable-vfs-bind-unbind-each: FAIL (0.034s)
[815.777969] [099/131] (206s left) kms_pm_lpsp (screens-disabled)
[816.884473] Starting subtest: screens-disabled
[816.889586] Subtest screens-disabled: SKIP (0.000s)
[816.978494] [100/131] (205s left) gem_exec_capture (capture-invisible)
[817.044227] Starting subtest: capture-invisible
[817.047012] Starting dynamic subtest: smem0
[817.052195] Dynamic subtest smem0: SKIP (0.000s)
[817.057379] Subtest capture-invisible: SKIP (0.001s)
[817.195081] [101/131] (204s left) gem_lmem_swapping (verify-ccs)
[817.240879] Subtest verify-ccs: SKIP (0.000s)
[817.291396] [102/131] (204s left) kms_joiner (basic-force-ultra-joiner)
[817.393364] Starting subtest: basic-force-ultra-joiner
[817.398743] Subtest basic-force-ultra-joiner: SKIP (0.000s)
[817.479975] [103/131] (204s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-wc)
[817.572471] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-wc
[824.071668] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.502s)
[824.213098] [104/131] (198s left) kms_color (legacy-gamma)
[824.316437] Starting subtest: legacy-gamma
[824.319190] Starting dynamic subtest: pipe-A-eDP-1
[825.035596] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.721s)
[825.046557] Starting dynamic subtest: pipe-B-eDP-1
[826.499640] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.461s)
[826.512585] Starting dynamic subtest: pipe-C-eDP-1
[827.860401] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.361s)
[827.871399] Starting dynamic subtest: pipe-D-eDP-1
[829.178326] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.317s)
[829.181192] Subtest legacy-gamma: SUCCESS (4.864s)
[830.012859] [105/131] (192s left) gem_exec_schedule (wide)
[830.201487] Starting subtest: wide
[830.204337] Starting dynamic subtest: rcs0
[840.899351] Dynamic subtest rcs0: SUCCESS (10.695s)
[840.912869] Starting dynamic subtest: vcs0
[851.488843] Dynamic subtest vcs0: SUCCESS (10.587s)
[851.500946] Starting dynamic subtest: vcs1
[862.775776] Dynamic subtest vcs1: SUCCESS (11.285s)
[862.790402] Starting dynamic subtest: vecs0
[873.051817] Dynamic subtest vecs0: SUCCESS (10.273s)
[873.068366] Starting dynamic subtest: ccs0
[888.522486] Dynamic subtest ccs0: SUCCESS (15.467s)
[888.525245] Subtest wide: SUCCESS (58.315s)
[889.896968] [106/131] (132s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-upscaling)
[890.021654] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-upscaling
[890.024670] Starting dynamic subtest: pipe-A-default-mode
[890.311482] Dynamic subtest pipe-A-default-mode: SKIP (0.287s)
[890.316306] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-upscaling: SKIP (0.288s)
[891.153734] [107/131] (131s left) kms_big_fb (linear-64bpp-rotate-180)
[891.247618] Starting subtest: linear-64bpp-rotate-180
[892.935582] Subtest linear-64bpp-rotate-180: SUCCESS (1.689s)
[893.117185] [108/131] (129s left) gem_ppgtt (blt-vs-render-ctxn)
[893.177194] Starting subtest: blt-vs-render-ctxN
[926.365280] Subtest blt-vs-render-ctxN: SUCCESS (33.187s)
[926.540505] [109/131] ( 95s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-plflip-blt)
[926.702070] Starting subtest: psr-2p-scndscrn-shrfb-plflip-blt
[926.707212] Subtest psr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[926.818308] [110/131] ( 95s left) kms_chamelium_frames (dp-frame-dump)
[927.205299] Subtest dp-frame-dump: SKIP (0.000s)
[928.020376] [111/131] ( 94s left) kms_psr2_su (page_flip-nv12)
[928.092107] Subtest page_flip-NV12: SKIP (0.000s)
[928.163082] [112/131] ( 94s left) gem_tiling_max_stride
[928.300024] [113/131] ( 94s left) perf_pmu (busy-idle)
[928.340777] Starting subtest: busy-idle
[928.343605] Starting dynamic subtest: rcs0
[929.029956] Dynamic subtest rcs0: SUCCESS (0.691s)
[929.047425] Starting dynamic subtest: bcs0
[929.699445] Dynamic subtest bcs0: SUCCESS (0.668s)
[929.712048] Starting dynamic subtest: vcs0
[931.729084] Dynamic subtest vcs0: SUCCESS (2.027s)
[931.743600] Starting dynamic subtest: vcs1
[933.778366] Dynamic subtest vcs1: SUCCESS (2.047s)
[933.792266] Starting dynamic subtest: vecs0
[935.762011] Dynamic subtest vecs0: SUCCESS (1.980s)
[935.775045] Starting dynamic subtest: ccs0
[936.436658] Dynamic subtest ccs0: SUCCESS (0.674s)
[936.439642] Subtest busy-idle: SUCCESS (8.100s)
[936.571047] [114/131] ( 86s left) dmabuf_sync_file (export-before-signal)
[936.623408] Starting subtest: export-before-signal
[936.625969] Subtest export-before-signal: SUCCESS (0.001s)
[936.702585] [115/131] ( 86s left) gem_exec_reloc (basic-cpu-read)
[936.777420] Subtest basic-cpu-read: SKIP (0.000s)
[936.864910] [116/131] ( 85s left) kms_hdr (bpc-switch)
[936.967306] Starting subtest: bpc-switch
[938.026378] Starting dynamic subtest: pipe-A-eDP-1
[941.521521] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.499s)
[941.524501] Subtest bpc-switch: SUCCESS (4.557s)
[941.686130] [117/131] ( 81s left) perf (i915-ref-count)
[941.800177] Starting subtest: i915-ref-count
[941.870313] Subtest i915-ref-count: SUCCESS (0.068s)
[941.930776] [118/131] ( 80s left) gem_ctx_isolation (nonpriv)
[942.002594] Starting subtest: nonpriv
[942.005432] Starting dynamic subtest: rcs0
[942.252516] Dynamic subtest rcs0: SUCCESS (0.249s)
[942.263434] Starting dynamic subtest: bcs0
[942.524489] Dynamic subtest bcs0: SUCCESS (0.271s)
[942.537899] Starting dynamic subtest: vcs0
[942.816251] Dynamic subtest vcs0: SUCCESS (0.291s)
[942.826365] Starting dynamic subtest: vcs1
[944.011368] Dynamic subtest vcs1: SUCCESS (1.194s)
[944.022018] Starting dynamic subtest: vecs0
[946.043337] Dynamic subtest vecs0: SUCCESS (2.031s)
[946.055889] Starting dynamic subtest: ccs0
[948.036888] Dynamic subtest ccs0: SUCCESS (1.992s)
[948.039467] Subtest nonpriv: SUCCESS (6.034s)
[948.321215] [119/131] ( 74s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[948.386459] Starting subtest: y-tiled-to-vebox-x-tiled
[948.392593] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[948.601910] [120/131] ( 74s left) kms_atomic_transition (plane-use-after-nonblocking-unbind)
[948.699585] Starting subtest: plane-use-after-nonblocking-unbind
[948.702427] Starting dynamic subtest: pipe-A-eDP-1
[948.761495] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.062s)
[949.057289] Starting dynamic subtest: pipe-B-eDP-1
[949.874389] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.828s)
[950.184200] Subtest plane-use-after-nonblocking-unbind: SUCCESS (1.485s)
[951.072716] [121/131] ( 71s left) i915_hangman (engine-error-state-capture)
[951.151128] Starting subtest: engine-error-state-capture
[951.153089] Starting dynamic subtest: rcs0
[956.719743] Dynamic subtest rcs0: SUCCESS (5.569s)
[956.733836] Starting dynamic subtest: bcs0
[962.735408] Dynamic subtest bcs0: SUCCESS (6.013s)
[962.749576] Starting dynamic subtest: vcs0
[968.688323] Dynamic subtest vcs0: SUCCESS (5.950s)
[968.702283] Starting dynamic subtest: vcs1
[973.680336] Dynamic subtest vcs1: SUCCESS (4.989s)
[973.691235] Starting dynamic subtest: vecs0
[979.698364] Dynamic subtest vecs0: SUCCESS (6.015s)
[979.712255] Starting dynamic subtest: ccs0
[985.712624] Dynamic subtest ccs0: SUCCESS (6.013s)
[985.715741] Subtest engine-error-state-capture: SUCCESS (34.564s)
[985.821194] [122/131] ( 37s left) gem_exec_schedule (pi-shared-iova)
[986.021131] Starting subtest: pi-shared-iova
[986.023755] Starting dynamic subtest: rcs0
[986.167569] Dynamic subtest rcs0: SUCCESS (0.149s)
[986.181610] Starting dynamic subtest: vcs0
[986.256946] Dynamic subtest vcs0: SUCCESS (0.089s)
[986.271824] Starting dynamic subtest: vcs1
[986.344784] Dynamic subtest vcs1: SUCCESS (0.087s)
[986.356189] Starting dynamic subtest: vecs0
[986.438400] Dynamic subtest vecs0: SUCCESS (0.093s)
[986.451846] Starting dynamic subtest: ccs0
[986.579454] Dynamic subtest ccs0: SUCCESS (0.139s)
[986.582820] Subtest pi-shared-iova: SUCCESS (0.562s)
[987.860709] [123/131] ( 35s left) syncobj_wait (invalid-reset-illegal-handle)
[987.930513] Starting subtest: invalid-reset-illegal-handle
[987.933508] Subtest invalid-reset-illegal-handle: SUCCESS (0.000s)
[988.069998] [124/131] ( 35s left) syncobj_wait (single-wait-all-for-submit-signaled)
[988.133278] Starting subtest: single-wait-all-for-submit-signaled
[988.136056] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[988.275317] [125/131] ( 34s left) kms_cursor_crc (cursor-sliding-512x170)
[988.547674] Starting subtest: cursor-sliding-512x170
[988.553205] Subtest cursor-sliding-512x170: SKIP (0.001s)
[988.739796] [126/131] ( 34s left) kms_chamelium_hpd (hdmi-hpd-fast)
[989.116604] Subtest hdmi-hpd-fast: SKIP (0.000s)
[989.960957] [127/131] ( 33s left) gem_ctx_persistence (hostile)
[990.024475] Starting subtest: hostile
[1000.194517] Subtest hostile: SUCCESS (10.160s)
[1000.362540] [128/131] ( 22s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt)
[1000.475452] Starting subtest: fbc-rgb565-draw-blt
[1004.686219] Subtest fbc-rgb565-draw-blt: SUCCESS (4.216s)
[1005.896827] [129/131] ( 17s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite)
[1006.010190] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite
[1015.236042] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (9.229s)
[1015.385637] [130/131] (  8s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[1015.489591] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[1015.495469] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[1015.634452] [131/131] (  7s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-move)
[1015.736394] Starting subtest: fbc-2p-scndscrn-spr-indfb-move
[1015.742346] Subtest fbc-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[1015.876301] Closing watchdogs
results: parsing output: 0/ for test: kms_big_fb
results: parsing output: 1/ for test: gem_exec_fence
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: gem_exec_balancer
results: parsing output: 5/ for test: kms_chamelium_color
results: parsing output: 6/ for test: gem_workarounds
results: parsing output: 7/ for test: kms_plane_scaling
results: parsing output: 8/ for test: gem_pxp
results: parsing output: 9/ for test: gem_exec_params
results: parsing output: 10/ for test: gem_exec_nop
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: kms_psr
results: parsing output: 13/ for test: i915_pm_rps
results: parsing output: 14/ for test: gem_softpin
results: parsing output: 15/ for test: syncobj_timeline
results: parsing output: 16/ for test: kms_panel_fitting
results: parsing output: 17/ for test: kms_big_fb
results: parsing output: 18/ for test: api_intel_bb
results: parsing output: 19/ for test: kms_ccs
results: parsing output: 20/ for test: syncobj_wait
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: gem_lmem_swapping
results: parsing output: 23/ for test: kms_async_flips
results: parsing output: 24/ for test: kms_psr2_sf
results: parsing output: 25/ for test: kms_plane_multiple
results: parsing output: 26/ for test: gem_ctx_param
results: parsing output: 27/ for test: kms_ccs
results: parsing output: 28/ for test: kms_plane_scaling
results: parsing output: 29/ for test: syncobj_timeline
results: parsing output: 30/ for test: perf
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: kms_pm_rpm
results: parsing output: 33/ for test: kms_vblank
results: parsing output: 34/ for test: kms_big_fb
results: parsing output: 35/ for test: kms_pipe_crc_basic
results: parsing output: 36/ for test: kms_cursor_legacy
results: parsing output: 37/ for test: kms_psr2_sf
results: parsing output: 38/ for test: gem_unref_active_buffers
results: parsing output: 39/ for test: kms_psr
results: parsing output: 40/ for test: syncobj_wait
results: parsing output: 41/ for test: gen9_exec_parse
results: parsing output: 42/ for test: syncobj_timeline
results: parsing output: 43/ for test: gem_exec_reloc
results: parsing output: 44/ for test: gem_mmap_offset
results: parsing output: 45/ for test: kms_cursor_legacy
results: parsing output: 46/ for test: kms_rotation_crc
results: parsing output: 47/ for test: kms_big_fb
results: parsing output: 48/ for test: gem_create
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: perf
results: parsing output: 51/ for test: perf
results: parsing output: 52/ for test: kms_big_fb
results: parsing output: 53/ for test: kms_cursor_crc
results: parsing output: 54/ for test: kms_rotation_crc
results: parsing output: 55/ for test: kms_cursor_crc
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: kms_async_flips
results: parsing output: 58/ for test: perf
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: syncobj_timeline
results: parsing output: 61/ for test: kms_rmfb
results: parsing output: 62/ for test: gem_userptr_blits
results: parsing output: 63/ for test: gem_exec_reloc
results: parsing output: 64/ for test: api_intel_allocator
results: parsing output: 65/ for test: kms_lease
results: parsing output: 66/ for test: kms_cursor_legacy
results: parsing output: 67/ for test: syncobj_wait
results: parsing output: 68/ for test: kms_psr
results: parsing output: 69/ for test: kms_ccs
results: parsing output: 70/ for test: kms_psr
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: kms_flip_scaled_crc
results: parsing output: 73/ for test: gem_exec_schedule
results: parsing output: 74/ for test: i915_drm_fdinfo
results: parsing output: 75/ for test: i915_selftest
results: parsing output: 76/ for test: gem_userptr_blits
results: parsing output: 77/ for test: kms_ccs
results: parsing output: 78/ for test: gem_softpin
results: parsing output: 79/ for test: kms_draw_crc
results: parsing output: 80/ for test: kms_big_fb
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: syncobj_timeline
results: parsing output: 83/ for test: gem_mmap_gtt
results: parsing output: 84/ for test: perf_pmu
results: parsing output: 85/ for test: kms_addfb_basic
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: gem_render_copy
results: parsing output: 88/ for test: kms_flip
results: parsing output: 89/ for test: gem_exec_schedule
results: parsing output: 90/ for test: kms_sequence
results: parsing output: 91/ for test: gem_gtt_cpu_tlb
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: gem_eio
results: parsing output: 94/ for test: gem_partial_pwrite_pread
results: parsing output: 95/ for test: sysfs_preempt_timeout
results: parsing output: 96/ for test: gem_busy
results: parsing output: 97/ for test: sriov_basic
results: parsing output: 98/ for test: kms_pm_lpsp
results: parsing output: 99/ for test: gem_exec_capture
results: parsing output: 100/ for test: gem_lmem_swapping
results: parsing output: 101/ for test: kms_joiner
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: kms_color
results: parsing output: 104/ for test: gem_exec_schedule
results: parsing output: 105/ for test: kms_flip_scaled_crc
results: parsing output: 106/ for test: kms_big_fb
results: parsing output: 107/ for test: gem_ppgtt
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: kms_chamelium_frames
results: parsing output: 110/ for test: kms_psr2_su
results: parsing output: 111/ for test: gem_tiling_max_stride
results: parsing output: 112/ for test: perf_pmu
results: parsing output: 113/ for test: dmabuf_sync_file
results: parsing output: 114/ for test: gem_exec_reloc
results: parsing output: 115/ for test: kms_hdr
results: parsing output: 116/ for test: perf
results: parsing output: 117/ for test: gem_ctx_isolation
results: parsing output: 118/ for test: gem_render_copy
results: parsing output: 119/ for test: kms_atomic_transition
results: parsing output: 120/ for test: i915_hangman
results: parsing output: 121/ for test: gem_exec_schedule
results: parsing output: 122/ for test: syncobj_wait
results: parsing output: 123/ for test: syncobj_wait
results: parsing output: 124/ for test: kms_cursor_crc
results: parsing output: 125/ for test: kms_chamelium_hpd
results: parsing output: 126/ for test: gem_ctx_persistence
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: kms_frontbuffer_tracking
Done.