[56.801137] Initializing watchdogs
[56.801184]   /dev/watchdog0
[56.815680] [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]
[56.820169] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.822953] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.826409] [001/131] (960s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[56.964225] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[56.968720] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.002s)
[57.091845] [002/131] (960s left) gem_exec_fence (submit3)
[57.278109] Starting subtest: submit3
[57.282559] Subtest submit3: SKIP (0.000s)
[59.013654] [FACT gem_exec_fence (submit3)] new: kernel.kmod_is_loaded.vgem: true
[59.017159] [003/131] (958s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[59.111131] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt
[59.115516] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[59.229177] [004/131] (958s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-wc)
[59.320034] Starting subtest: fbc-rgb101010-draw-mmap-wc
[63.890028] Subtest fbc-rgb101010-draw-mmap-wc: SUCCESS (4.589s)
[65.098758] [005/131] (952s left) gem_exec_balancer (noheartbeat)
[65.368529] Starting subtest: noheartbeat
[65.373012] Subtest noheartbeat: SKIP (0.000s)
[65.496331] [006/131] (952s left) kms_chamelium_color (ctm-blue-to-red)
[65.610254] Subtest ctm-blue-to-red: SKIP (0.000s)
[65.652679] [007/131] (951s left) gem_workarounds (suspend-resume)
[65.733104] Starting subtest: suspend-resume
[82.135565] Subtest suspend-resume: SUCCESS (5.618s)
[82.300554] [008/131] (935s left) kms_plane_scaling (planes-downscale-factor-0-75-unity-scaling)
[82.423470] Starting subtest: planes-downscale-factor-0-75-unity-scaling
[82.425801] Starting dynamic subtest: pipe-A
[82.487322] Dynamic subtest pipe-A: SUCCESS (0.066s)
[82.498620] Starting dynamic subtest: pipe-B
[83.588266] Dynamic subtest pipe-B: SUCCESS (1.136s)
[83.598747] Starting dynamic subtest: pipe-C
[84.692508] Dynamic subtest pipe-C: SUCCESS (1.136s)
[84.702533] Starting dynamic subtest: pipe-D
[85.788189] Dynamic subtest pipe-D: SUCCESS (1.123s)
[85.790654] Subtest planes-downscale-factor-0-75-unity-scaling: SUCCESS (3.463s)
[86.951008] [009/131] (930s left) gem_pxp (create-regular-buffer)
[87.077113] Starting subtest: create-regular-buffer
[87.079139] Subtest create-regular-buffer: SUCCESS (0.000s)
[87.202630] [010/131] (930s left) gem_exec_params (rel-constants-invalid-rel-gen5)
[87.263806] Starting subtest: rel-constants-invalid-rel-gen5
[87.266233] Subtest rel-constants-invalid-rel-gen5: SUCCESS (0.000s)
[87.381443] [011/131] (930s left) gem_exec_nop (basic-series)
[87.450420] Starting subtest: basic-series
[103.684436] Subtest basic-series: SUCCESS (16.372s)
[103.949560] [012/131] (913s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-wc)
[104.069990] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-wc
[104.074011] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[104.186935] [013/131] (913s left) kms_psr (fbc-psr-primary-page-flip)
[104.289653] Starting subtest: fbc-psr-primary-page-flip
[104.291960] Starting dynamic subtest: eDP-1
[104.296280] Dynamic subtest eDP-1: SKIP (0.000s)
[104.300554] Subtest fbc-psr-primary-page-flip: SKIP (0.000s)
[104.388311] [014/131] (913s left) i915_pm_rps (engine-order)
[104.451732] Starting subtest: engine-order
[109.318706] Subtest engine-order: SUCCESS (4.874s)
[109.480450] [015/131] (908s left) gem_softpin (evict-active)
[109.550718] Starting subtest: evict-active
[109.561133] Subtest evict-active: SUCCESS (0.010s)
[109.720009] [016/131] (908s left) syncobj_timeline (wait-delayed-signal)
[109.784767] Starting subtest: wait-delayed-signal
[109.887335] Subtest wait-delayed-signal: SUCCESS (0.102s)
[109.997814] [017/131] (907s left) kms_panel_fitting (atomic-fastset)
[110.094697] Starting subtest: atomic-fastset
[110.098289] Starting dynamic subtest: pipe-A-eDP-1
[110.236167] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.144s)
[110.247811] Starting dynamic subtest: pipe-B-eDP-1
[111.448727] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.213s)
[111.458559] Starting dynamic subtest: pipe-C-eDP-1
[112.643595] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.194s)
[112.652385] Starting dynamic subtest: pipe-D-eDP-1
[113.862291] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.218s)
[113.864575] Subtest atomic-fastset: SUCCESS (3.774s)
[115.137848] [018/131] (902s left) kms_big_fb (x-tiled-16bpp-rotate-270)
[115.252805] Starting subtest: x-tiled-16bpp-rotate-270
[115.272628] Subtest x-tiled-16bpp-rotate-270: SKIP (0.019s)
[115.421046] [019/131] (902s left) api_intel_bb (render-ccs)
[115.480760] Starting subtest: render-ccs
[115.834737] Subtest render-ccs: SUCCESS (0.354s)
[115.966731] [020/131] (902s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc)
[116.061073] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc
[116.063575] Starting dynamic subtest: pipe-A-eDP-1
[116.086981] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[116.098565] Starting dynamic subtest: pipe-B-eDP-1
[116.120256] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[116.128941] Starting dynamic subtest: pipe-C-eDP-1
[116.150702] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[116.158957] Starting dynamic subtest: pipe-D-eDP-1
[116.180459] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[116.184881] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc: SKIP (0.005s)
[118.397863] [021/131] (899s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted-signaled)
[118.442759] Starting subtest: multi-wait-all-for-submit-unsubmitted-signaled
[118.549304] Subtest multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.105s)
[118.646720] [022/131] (899s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-move)
[118.749352] Starting subtest: psr-2p-primscrn-cur-indfb-move
[118.753505] Subtest psr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[118.894259] [023/131] (899s left) gem_lmem_swapping (heavy-multi)
[118.933954] Subtest heavy-multi: SKIP (0.000s)
[118.977539] [024/131] (899s left) kms_async_flips (test-cursor)
[119.091282] Starting subtest: test-cursor
[119.095434] Subtest test-cursor: SKIP (0.000s)
[120.320692] [025/131] (897s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-sf)
[125.283266] Starting subtest: fbc-psr2-overlay-plane-move-continuous-exceed-sf
[125.285724] Starting dynamic subtest: pipe-A-eDP-1
[126.094280] Dynamic subtest pipe-A-eDP-1: SKIP (0.808s)
[126.104436] Starting dynamic subtest: pipe-B-eDP-1
[127.222798] Dynamic subtest pipe-B-eDP-1: SKIP (1.125s)
[127.227216] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-sf: SKIP (1.937s)
[128.435076] [026/131] (889s left) kms_plane_multiple (2x-tiling-4)
[128.526689] Starting subtest: 2x-tiling-4
[128.531041] Subtest 2x-tiling-4: SKIP (0.000s)
[128.622365] [027/131] (889s left) gem_ctx_param (invalid-param-set)
[128.682896] Starting subtest: invalid-param-set
[128.685066] Subtest invalid-param-set: SUCCESS (0.000s)
[128.778326] [028/131] (889s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[128.874237] Starting subtest: bad-pixel-format-y-tiled-ccs
[128.876504] Starting dynamic subtest: pipe-A-eDP-1
[128.899037] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[128.910578] Starting dynamic subtest: pipe-B-eDP-1
[128.931040] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[128.940076] Starting dynamic subtest: pipe-C-eDP-1
[128.960876] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[128.969971] Starting dynamic subtest: pipe-D-eDP-1
[128.990946] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[128.994887] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.003s)
[131.123886] [029/131] (887s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[131.225899] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[131.228227] Starting dynamic subtest: pipe-A
[131.245635] Dynamic subtest pipe-A: SKIP (0.017s)
[131.256026] Starting dynamic subtest: pipe-B
[131.277984] Dynamic subtest pipe-B: SKIP (0.031s)
[131.287009] Starting dynamic subtest: pipe-C
[131.308212] Dynamic subtest pipe-C: SKIP (0.031s)
[131.317041] Starting dynamic subtest: pipe-D
[131.339320] Dynamic subtest pipe-D: SKIP (0.030s)
[131.343130] Subtest plane-downscale-factor-0-5-with-modifiers: SKIP (0.110s)
[131.476991] [030/131] (887s left) syncobj_timeline (multi-wait-all-available-submitted-signaled)
[131.543848] Starting subtest: multi-wait-all-available-submitted-signaled
[131.545897] Subtest multi-wait-all-available-submitted-signaled: SUCCESS (0.000s)
[131.679201] [031/131] (886s left) perf (missing-sample-flags)
[131.834663] Starting subtest: missing-sample-flags
[131.836603] Subtest missing-sample-flags: SUCCESS (0.000s)
[131.876492] [032/131] (886s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-pwrite)
[131.983218] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-pwrite
[138.122902] Subtest fbc-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (6.143s)
[139.310753] [033/131] (879s left) kms_pm_rpm (system-suspend-idle)
[140.864925] Starting subtest: system-suspend-idle
[160.332723] Subtest system-suspend-idle: SUCCESS (4.355s)
[160.728243] [034/131] (857s left) kms_vblank (wait-forked)
[160.842244] Starting subtest: wait-forked
[160.844547] Starting dynamic subtest: pipe-A-eDP-1
[162.621034] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.822s)
[162.636290] Starting dynamic subtest: pipe-D-eDP-1
[165.110640] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.533s)
[165.112939] Subtest wait-forked: SUCCESS (4.359s)
[165.979583] [035/131] (852s left) kms_big_fb (y-tiled-32bpp-rotate-0)
[166.101459] Starting subtest: y-tiled-32bpp-rotate-0
[166.105714] Subtest y-tiled-32bpp-rotate-0: SKIP (0.000s)
[166.223454] [036/131] (852s left) kms_pipe_crc_basic (read-crc)
[166.318725] Starting subtest: read-crc
[166.320964] Starting dynamic subtest: pipe-A-eDP-1
[167.039486] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.728s)
[167.049609] Starting dynamic subtest: pipe-B-eDP-1
[168.411714] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.386s)
[168.422346] Starting dynamic subtest: pipe-C-eDP-1
[169.795677] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.397s)
[169.805752] Starting dynamic subtest: pipe-D-eDP-1
[171.106484] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.319s)
[171.108763] Subtest read-crc: SUCCESS (4.834s)
[171.944042] [037/131] (846s left) kms_cursor_legacy (single-move)
[172.060713] Starting subtest: single-move
[172.062649] Starting dynamic subtest: pipe-A
[177.419459] Dynamic subtest pipe-A: SUCCESS (5.380s)
[177.431461] Starting dynamic subtest: pipe-B
[182.788326] Dynamic subtest pipe-B: SUCCESS (5.381s)
[182.799839] Starting dynamic subtest: pipe-C
[188.163282] Dynamic subtest pipe-C: SUCCESS (5.380s)
[188.174609] Starting dynamic subtest: pipe-D
[193.542754] Dynamic subtest pipe-D: SUCCESS (5.380s)
[193.554483] Starting dynamic subtest: all-pipes
[198.934744] Dynamic subtest all-pipes: SUCCESS (5.387s)
[198.937380] Subtest single-move: SUCCESS (26.917s)
[199.103191] [038/131] (819s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-sf)
[204.061752] Starting subtest: pr-overlay-plane-move-continuous-exceed-sf
[204.066308] Subtest pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.002s)
[204.915735] [039/131] (813s left) gem_unref_active_buffers
[205.167043] [040/131] (813s left) kms_psr (fbc-psr2-primary-mmap-gtt)
[205.268311] Starting subtest: fbc-psr2-primary-mmap-gtt
[205.270604] Starting dynamic subtest: eDP-1
[205.275035] Dynamic subtest eDP-1: SKIP (0.000s)
[205.279600] Subtest fbc-psr2-primary-mmap-gtt: SKIP (0.001s)
[205.367184] [041/131] (813s left) syncobj_wait (invalid-signal-zero-handles)
[205.427463] Starting subtest: invalid-signal-zero-handles
[205.429715] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[205.561848] [042/131] (813s left) gen9_exec_parse (bb-oversize)
[205.624128] Subtest bb-oversize: SKIP (0.000s)
[205.699280] [043/131] (813s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted)
[205.760778] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted
[205.867376] Subtest etime-multi-wait-all-for-submit-available-unsubmitted: SUCCESS (0.106s)
[205.968195] [044/131] (813s left) gem_exec_reloc (basic-write-cpu-noreloc)
[206.026447] Subtest basic-write-cpu-noreloc: SKIP (0.000s)
[206.099549] [045/131] (813s left) gem_mmap_offset (bad-flags)
[206.155864] Starting subtest: bad-flags
[206.158007] Subtest bad-flags: SUCCESS (0.000s)
[206.283209] [046/131] (812s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[206.408626] Starting subtest: cursor-vs-flip-legacy
[235.734428] Subtest cursor-vs-flip-legacy: SUCCESS (29.330s)
[236.557057] [047/131] (782s left) kms_rotation_crc (sprite-rotation-90-pos-100-0)
[236.661160] Starting subtest: sprite-rotation-90-pos-100-0
[236.665643] Subtest sprite-rotation-90-pos-100-0: SKIP (0.001s)
[236.761542] [048/131] (782s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[236.873157] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip
[237.884728] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip: SUCCESS (1.011s)
[238.049448] [049/131] (781s left) gem_create (busy-create)
[238.108976] Starting subtest: busy-create
[238.111281] Starting dynamic subtest: smem0
[271.914248] Dynamic subtest smem0: SUCCESS (33.802s)
[271.916921] Subtest busy-create: SUCCESS (33.804s)
[272.127487] [050/131] (747s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-render)
[272.228521] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-render
[276.402493] Subtest fbc-1p-offscreen-pri-indfb-draw-render: SUCCESS (4.176s)
[277.557726] [051/131] (741s left) perf (invalid-open-flags)
[277.665191] Starting subtest: invalid-open-flags
[277.667729] Subtest invalid-open-flags: SUCCESS (0.000s)
[277.706725] [052/131] (741s left) perf (oa-formats)
[277.815667] Starting subtest: oa-formats
[277.818001] Starting dynamic subtest: 0-rcs0
[277.830707] Dynamic subtest 0-rcs0: SUCCESS (0.014s)
[277.842625] Starting dynamic subtest: 1-vecs0
[277.853127] Dynamic subtest 1-vecs0: SUCCESS (0.022s)
[277.855487] Subtest oa-formats: SUCCESS (0.038s)
[277.899231] [053/131] (741s left) kms_big_fb (4-tiled-8bpp-rotate-270)
[278.000762] Starting subtest: 4-tiled-8bpp-rotate-270
[278.021330] Subtest 4-tiled-8bpp-rotate-270: SKIP (0.020s)
[278.184845] [054/131] (741s left) kms_cursor_crc (cursor-onscreen-64x21)
[278.370892] Starting subtest: cursor-onscreen-64x21
[278.418482] Subtest cursor-onscreen-64x21: SKIP (0.043s)
[278.674780] [055/131] (740s left) kms_rotation_crc (primary-4-tiled-reflect-x-180)
[278.767347] Starting subtest: primary-4-tiled-reflect-x-180
[285.873147] Subtest primary-4-tiled-reflect-x-180: SUCCESS (7.104s)
[287.112615] [056/131] (732s left) kms_cursor_crc (cursor-onscreen-256x256)
[287.351544] Starting subtest: cursor-onscreen-256x256
[287.356905] Starting dynamic subtest: pipe-A-eDP-1
[289.466780] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.109s)
[289.495604] Starting dynamic subtest: pipe-D-eDP-1
[292.253628] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.765s)
[292.255852] Subtest cursor-onscreen-256x256: SUCCESS (4.901s)
[293.084790] [057/131] (726s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-msflip-blt)
[293.187770] Starting subtest: fbcpsr-1p-primscrn-shrfb-msflip-blt
[300.607374] Subtest fbcpsr-1p-primscrn-shrfb-msflip-blt: SUCCESS (7.423s)
[300.802625] [058/131] (718s left) kms_async_flips (async-flip-with-page-flip-events-tiled)
[300.904168] Starting subtest: async-flip-with-page-flip-events-tiled
[301.274921] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs
[304.049871] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: SUCCESS (2.775s)
[304.059860] Starting dynamic subtest: pipe-A-eDP-1-4
[306.224745] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.174s)
[306.234849] Starting dynamic subtest: pipe-A-eDP-1-4
[308.234927] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.009s)
[308.247525] Starting dynamic subtest: pipe-A-eDP-1-x
[310.413654] Dynamic subtest pipe-A-eDP-1-x: SUCCESS (2.178s)
[310.800190] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs
[313.576150] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: SUCCESS (2.784s)
[313.588688] Starting dynamic subtest: pipe-B-eDP-1-4
[315.761318] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.185s)
[315.773739] Starting dynamic subtest: pipe-B-eDP-1-4
[317.771426] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.009s)
[317.783756] Starting dynamic subtest: pipe-B-eDP-1-x
[319.961504] Dynamic subtest pipe-B-eDP-1-x: SUCCESS (2.190s)
[320.355955] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs
[323.109732] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.761s)
[323.122672] Starting dynamic subtest: pipe-C-eDP-1-4
[325.284347] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.174s)
[325.296947] Starting dynamic subtest: pipe-C-eDP-1-4
[327.294028] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.009s)
[327.303991] Starting dynamic subtest: pipe-C-eDP-1-x
[329.483876] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.189s)
[329.846462] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs
[332.637479] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.800s)
[332.647732] Starting dynamic subtest: pipe-D-eDP-1-4
[334.810122] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.172s)
[334.819570] Starting dynamic subtest: pipe-D-eDP-1-4
[336.820179] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.009s)
[336.830157] Starting dynamic subtest: pipe-D-eDP-1-x
[338.998279] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.178s)
[339.000523] Subtest async-flip-with-page-flip-events-tiled: SUCCESS (38.100s)
[340.234498] [059/131] (679s left) perf (polling)
[340.343830] Starting subtest: polling
[340.346039] Starting dynamic subtest: 0-rcs0
[350.362424] Dynamic subtest 0-rcs0: SUCCESS (10.022s)
[350.377901] Starting dynamic subtest: 1-vecs0
[360.378025] Dynamic subtest 1-vecs0: SUCCESS (10.016s)
[360.380440] Subtest polling: SUCCESS (20.041s)
[360.439987] [060/131] (659s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[360.543594] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[360.547533] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[360.683917] [061/131] (659s left) syncobj_timeline (reset-signaled)
[360.751768] Starting subtest: reset-signaled
[360.754038] Subtest reset-signaled: SUCCESS (0.000s)
[360.890887] [062/131] (658s left) kms_rmfb (rmfb-ioctl)
[360.992464] Starting subtest: rmfb-ioctl
[360.994428] Starting dynamic subtest: pipe-A-eDP-1
[361.097257] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.106s)
[361.109709] Starting dynamic subtest: pipe-B-eDP-1
[362.257558] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.158s)
[362.270099] Starting dynamic subtest: pipe-C-eDP-1
[363.385644] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.126s)
[363.399147] Starting dynamic subtest: pipe-D-eDP-1
[364.512856] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.125s)
[364.515486] Subtest rmfb-ioctl: SUCCESS (3.525s)
[365.741835] [063/131] (654s left) gem_userptr_blits (create-destroy-sync)
[365.819281] Starting subtest: create-destroy-sync
[370.835065] Subtest create-destroy-sync: SUCCESS (5.018s)
[370.997650] [064/131] (648s left) gem_exec_reloc (basic-gtt-cpu-active)
[371.049990] Subtest basic-gtt-cpu-active: SKIP (0.000s)
[371.123860] [065/131] (648s left) api_intel_allocator (execbuf-with-allocator)
[371.177681] Starting subtest: execbuf-with-allocator
[371.183874] Subtest execbuf-with-allocator: SUCCESS (0.009s)
[371.343449] [066/131] (648s left) kms_lease (cursor-implicit-plane)
[371.453448] Starting subtest: cursor-implicit-plane
[371.455807] Starting dynamic subtest: pipe-A-eDP-1
[371.911825] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.457s)
[371.921822] Starting dynamic subtest: pipe-B-eDP-1
[373.044038] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.131s)
[373.054173] Starting dynamic subtest: pipe-C-eDP-1
[374.156990] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.113s)
[374.167598] Starting dynamic subtest: pipe-D-eDP-1
[375.274290] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.116s)
[375.276681] Subtest cursor-implicit-plane: SUCCESS (3.824s)
[376.156634] [067/131] (643s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions-varying-size)
[376.279437] Starting subtest: cursorA-vs-flipB-atomic-transitions-varying-size
[376.284866] Subtest cursorA-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[376.448116] [068/131] (643s left) syncobj_wait (invalid-signal-illegal-handle)
[376.505462] Starting subtest: invalid-signal-illegal-handle
[376.507695] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[376.640527] [069/131] (643s left) kms_psr (psr-primary-blt)
[376.739372] Starting subtest: psr-primary-blt
[376.741619] Starting dynamic subtest: eDP-1
[378.434479] Dynamic subtest eDP-1: SUCCESS (1.697s)
[378.436800] Subtest psr-primary-blt: SUCCESS (1.698s)
[379.321768] [070/131] (640s left) kms_ccs (crc-sprite-planes-basic-y-tiled-gen12-rc-ccs)
[379.419203] Starting subtest: crc-sprite-planes-basic-y-tiled-gen12-rc-ccs
[379.421369] Starting dynamic subtest: pipe-A-eDP-1
[379.477553] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[379.487038] Starting dynamic subtest: pipe-B-eDP-1
[379.543603] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[379.554065] Starting dynamic subtest: pipe-C-eDP-1
[379.611437] Dynamic subtest pipe-C-eDP-1: SKIP (0.003s)
[379.621917] Starting dynamic subtest: pipe-D-eDP-1
[379.679092] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[379.683485] Subtest crc-sprite-planes-basic-y-tiled-gen12-rc-ccs: SKIP (0.013s)
[381.695343] [071/131] (638s left) kms_psr (pr-primary-blt)
[381.799633] Starting subtest: pr-primary-blt
[381.804053] Subtest pr-primary-blt: SKIP (0.000s)
[381.893924] [072/131] (638s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-move)
[381.994880] Starting subtest: psr-2p-primscrn-spr-indfb-move
[381.999228] Subtest psr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[382.124570] [073/131] (638s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[382.221716] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[382.223984] Starting dynamic subtest: pipe-A-default-mode
[383.345491] Dynamic subtest pipe-A-default-mode: SKIP (1.122s)
[383.349773] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SKIP (1.123s)
[383.506084] [074/131] (636s left) gem_exec_schedule (u-semaphore-noskip)
[383.583227] Starting subtest: u-semaphore-noskip
[383.970855] Subtest u-semaphore-noskip: SUCCESS (0.395s)
[384.335968] [075/131] (636s left) i915_drm_fdinfo (memory-info-idle)
[384.375305] Starting subtest: memory-info-idle
[384.377647] Starting dynamic subtest: smem0
[384.445033] Dynamic subtest smem0: SUCCESS (0.071s)
[384.447645] Subtest memory-info-idle: SUCCESS (0.072s)
[384.543880] [076/131] (635s left) i915_selftest (live)
[388.137082] Starting subtest: live
[388.140072] Starting dynamic subtest: sanitycheck
[392.616643] Dynamic subtest sanitycheck: SUCCESS (4.478s)
[392.626652] Starting dynamic subtest: uncore
[397.097668] Dynamic subtest uncore: SUCCESS (4.477s)
[397.107213] Starting dynamic subtest: workarounds
[414.957468] Dynamic subtest workarounds: FAIL (17.371s)
[414.966664] Starting dynamic subtest: gt_engines
[419.205445] Dynamic subtest gt_engines: SUCCESS (4.729s)
[419.215520] Starting dynamic subtest: gt_timelines
[435.184652] Dynamic subtest gt_timelines: SUCCESS (15.976s)
[435.194936] Starting dynamic subtest: gt_contexts
[438.094480] Dynamic subtest gt_contexts: SUCCESS (2.907s)
[438.104907] Starting dynamic subtest: gt_lrc
[444.410440] Dynamic subtest gt_lrc: SUCCESS (6.313s)
[444.420212] Starting dynamic subtest: gt_mocs
[447.585445] Dynamic subtest gt_mocs: SUCCESS (3.174s)
[447.595335] Starting dynamic subtest: gt_pm
[454.491376] Dynamic subtest gt_pm: SUCCESS (6.903s)
[454.506747] Starting dynamic subtest: gt_heartbeat
[458.985635] Dynamic subtest gt_heartbeat: SUCCESS (4.492s)
[458.995985] Starting dynamic subtest: gt_tlb
[468.518783] Dynamic subtest gt_tlb: SUCCESS (9.531s)
[468.529006] Starting dynamic subtest: requests
[482.030251] Dynamic subtest requests: SUCCESS (13.510s)
[482.040320] Starting dynamic subtest: migrate
[490.135954] Dynamic subtest migrate: SUCCESS (8.101s)
[490.146001] Starting dynamic subtest: active
[494.625737] Dynamic subtest active: SUCCESS (4.491s)
[494.636123] Starting dynamic subtest: objects
[499.462908] Dynamic subtest objects: SUCCESS (4.835s)
[499.473426] Starting dynamic subtest: mman
[504.487477] Dynamic subtest mman: SUCCESS (5.023s)
[504.497636] Starting dynamic subtest: dmabuf
[509.037822] Dynamic subtest dmabuf: SUCCESS (4.549s)
[509.047987] Starting dynamic subtest: vma
[513.551867] Dynamic subtest vma: SUCCESS (4.513s)
[513.562046] Starting dynamic subtest: coherency
[518.758333] Dynamic subtest coherency: SUCCESS (5.204s)
[518.768482] Starting dynamic subtest: gtt
[531.073130] Dynamic subtest gtt: SUCCESS (12.314s)
[531.082699] Starting dynamic subtest: gem
[538.776413] Dynamic subtest gem: SUCCESS (7.703s)
[538.786005] Starting dynamic subtest: evict
[544.285725] Dynamic subtest evict: SUCCESS (5.509s)
[544.295869] Starting dynamic subtest: hugepages
[555.470820] Dynamic subtest hugepages: SUCCESS (11.184s)
[555.480669] Starting dynamic subtest: gem_contexts
[582.959309] Dynamic subtest gem_contexts: SUCCESS (27.491s)
[582.969244] Starting dynamic subtest: client
[587.463439] Dynamic subtest client: SUCCESS (4.503s)
[587.475136] Starting dynamic subtest: gem_migrate
[592.059010] Dynamic subtest gem_migrate: SUCCESS (4.593s)
[592.069377] Starting dynamic subtest: reset
[595.003804] Dynamic subtest reset: SUCCESS (2.943s)
[595.014026] Starting dynamic subtest: memory_region
[599.512420] Dynamic subtest memory_region: SUCCESS (4.507s)
[599.522225] Starting dynamic subtest: hangcheck
[614.942034] Dynamic subtest hangcheck: SUCCESS (15.431s)
[614.952364] Starting dynamic subtest: execlists
[619.367923] Dynamic subtest execlists: SUCCESS (4.424s)
[619.377988] Starting dynamic subtest: ring_submission
[623.878452] Dynamic subtest ring_submission: SUCCESS (4.509s)
[623.888494] Starting dynamic subtest: perf
[628.358878] Dynamic subtest perf: SUCCESS (4.478s)
[628.368987] Starting dynamic subtest: slpc
[642.296880] Dynamic subtest slpc: SUCCESS (13.936s)
[642.307092] Starting dynamic subtest: guc
[646.368652] Dynamic subtest guc: SUCCESS (4.067s)
[646.384148] Starting dynamic subtest: guc_multi_lrc
[651.001125] Dynamic subtest guc_multi_lrc: SUCCESS (4.627s)
[651.011531] Starting dynamic subtest: guc_hang
[656.082679] Dynamic subtest guc_hang: SUCCESS (5.076s)
[656.092920] Starting dynamic subtest: late_gt_pm
[658.999506] Dynamic subtest late_gt_pm: SUCCESS (2.913s)
[659.002244] Subtest live: FAIL (270.861s)
[659.032702] [FACT i915_selftest (live)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[659.033483] [FACT i915_selftest (live)] deleted: kernel.kmod_is_loaded.i915: true
[659.036855] [077/131] (361s left) gem_userptr_blits (nohangcheck)
[660.607415] Starting subtest: nohangcheck
[660.844992] Subtest nohangcheck: SUCCESS (0.235s)
[660.928801] [FACT gem_userptr_blits (nohangcheck)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[660.930074] [FACT gem_userptr_blits (nohangcheck)] new: kernel.kmod_is_loaded.i915: true
[660.933947] [078/131] (359s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs)
[661.035454] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs
[661.037776] Starting dynamic subtest: pipe-A-eDP-1
[661.875741] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.833s)
[661.888627] Starting dynamic subtest: pipe-B-eDP-1
[663.519465] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.639s)
[663.529596] Starting dynamic subtest: pipe-C-eDP-1
[665.045444] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.524s)
[665.055997] Starting dynamic subtest: pipe-D-eDP-1
[666.596330] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.548s)
[666.598708] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs: SUCCESS (5.549s)
[667.864328] [079/131] (352s left) gem_softpin (allocator-nopin)
[667.924457] Starting subtest: allocator-nopin
[668.033678] Subtest allocator-nopin: SUCCESS (0.111s)
[668.199839] [080/131] (352s left) kms_draw_crc (draw-method-pwrite)
[668.293116] Starting subtest: draw-method-pwrite
[668.295342] Starting dynamic subtest: xrgb8888-untiled
[668.892462] Dynamic subtest xrgb8888-untiled: SUCCESS (0.600s)
[668.902236] Starting dynamic subtest: rgb565-untiled
[669.349847] Dynamic subtest rgb565-untiled: SUCCESS (0.455s)
[669.359735] Starting dynamic subtest: xrgb2101010-untiled
[669.904305] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.555s)
[669.913873] Starting dynamic subtest: xbgr16161616f-untiled
[670.704863] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.799s)
[670.714151] Starting dynamic subtest: xrgb8888-xtiled
[672.483223] Dynamic subtest xrgb8888-xtiled: SUCCESS (1.776s)
[672.495498] Starting dynamic subtest: rgb565-xtiled
[673.561545] Dynamic subtest rgb565-xtiled: SUCCESS (1.075s)
[673.571539] Starting dynamic subtest: xrgb2101010-xtiled
[675.329019] Dynamic subtest xrgb2101010-xtiled: SUCCESS (1.766s)
[675.338693] Starting dynamic subtest: xbgr16161616f-xtiled
[678.608395] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (3.277s)
[678.618120] Starting dynamic subtest: xrgb8888-4tiled
[679.698036] Dynamic subtest xrgb8888-4tiled: SUCCESS (1.088s)
[679.707983] Starting dynamic subtest: rgb565-4tiled
[680.253535] Dynamic subtest rgb565-4tiled: SUCCESS (0.555s)
[680.263419] Starting dynamic subtest: xrgb2101010-4tiled
[681.332435] Dynamic subtest xrgb2101010-4tiled: SUCCESS (1.077s)
[681.347560] Starting dynamic subtest: xbgr16161616f-4tiled
[684.288704] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (2.953s)
[684.291033] Subtest draw-method-pwrite: SUCCESS (15.990s)
[684.418492] [081/131] (336s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[684.515725] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip
[684.520159] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[684.648041] [082/131] (336s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-plflip-blt)
[684.743006] Starting subtest: fbc-2p-primscrn-indfb-plflip-blt
[684.747100] Subtest fbc-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[684.869529] [083/131] (335s left) syncobj_timeline (host-signal-points)
[684.921378] Starting subtest: host-signal-points
[684.924030] Subtest host-signal-points: SUCCESS (0.004s)
[685.100510] [084/131] (335s left) gem_mmap_gtt (basic-small-bo)
[685.168353] Subtest basic-small-bo: SKIP (0.000s)
[685.244027] [085/131] (335s left) perf_pmu (semaphore-wait-idle)
[685.280635] Starting subtest: semaphore-wait-idle
[685.282667] Starting dynamic subtest: rcs0
[685.800452] Dynamic subtest rcs0: SUCCESS (0.515s)
[685.810042] Starting dynamic subtest: bcs0
[686.310174] Dynamic subtest bcs0: SUCCESS (0.511s)
[686.321018] Starting dynamic subtest: vcs0
[686.823819] Dynamic subtest vcs0: SUCCESS (0.511s)
[686.834243] Starting dynamic subtest: vcs1
[687.337322] Dynamic subtest vcs1: SUCCESS (0.510s)
[687.348320] Starting dynamic subtest: vecs0
[687.852945] Dynamic subtest vecs0: SUCCESS (0.512s)
[687.867905] Starting dynamic subtest: ccs0
[688.367753] Dynamic subtest ccs0: SUCCESS (0.512s)
[688.370682] Subtest semaphore-wait-idle: SUCCESS (3.088s)
[689.900690] [086/131] (330s left) kms_addfb_basic (no-handle)
[689.973386] Starting subtest: no-handle
[689.975678] Subtest no-handle: SUCCESS (0.000s)
[690.147393] [087/131] (330s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-pwrite)
[690.256596] Starting subtest: fbcpsr-rgb101010-draw-pwrite
[697.494649] Subtest fbcpsr-rgb101010-draw-pwrite: SUCCESS (7.237s)
[697.636126] [088/131] (323s left) gem_render_copy (yf-tiled-ccs-to-linear)
[697.701891] Starting subtest: yf-tiled-ccs-to-linear
[697.706587] Subtest yf-tiled-ccs-to-linear: SKIP (0.000s)
[697.912011] [089/131] (323s left) kms_flip (wf_vblank-ts-check)
[698.009477] Starting subtest: wf_vblank-ts-check
[698.011777] Starting dynamic subtest: A-eDP1
[706.950318] Dynamic subtest A-eDP1: SUCCESS (8.937s)
[706.960995] Starting dynamic subtest: B-eDP1
[715.878152] Dynamic subtest B-eDP1: SUCCESS (8.925s)
[715.888488] Starting dynamic subtest: C-eDP1
[724.794311] Dynamic subtest C-eDP1: SUCCESS (8.912s)
[724.803986] Starting dynamic subtest: D-eDP1
[733.727596] Dynamic subtest D-eDP1: SUCCESS (8.930s)
[733.733919] Subtest wf_vblank-ts-check: SUCCESS (35.715s)
[734.976117] [090/131] (286s left) gem_exec_schedule (deep)
[735.166047] Starting subtest: deep
[735.168448] Starting dynamic subtest: rcs0
[740.054537] Dynamic subtest rcs0: SKIP (4.881s)
[740.073234] Starting dynamic subtest: vcs0
[750.351201] Dynamic subtest vcs0: SUCCESS (10.303s)
[750.364328] Starting dynamic subtest: vcs1
[762.230001] Dynamic subtest vcs1: SUCCESS (11.876s)
[762.243851] Starting dynamic subtest: vecs0
[773.341276] Dynamic subtest vecs0: SUCCESS (11.110s)
[773.355027] Starting dynamic subtest: ccs0
[786.226044] Dynamic subtest ccs0: SUCCESS (12.883s)
[786.228271] Subtest deep: SUCCESS (51.059s)
[787.559111] [091/131] (233s left) kms_sequence (queue-idle)
[787.678336] Starting subtest: queue-idle
[787.680588] Starting dynamic subtest: pipe-A-eDP-1
[789.496335] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.816s)
[789.506378] Starting dynamic subtest: pipe-B-eDP-1
[791.934583] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.440s)
[791.945427] Starting dynamic subtest: pipe-C-eDP-1
[794.348983] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.412s)
[794.360091] Starting dynamic subtest: pipe-D-eDP-1
[796.790951] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.440s)
[796.793338] Subtest queue-idle: SUCCESS (9.114s)
[797.616216] [092/131] (223s left) gem_gtt_cpu_tlb
[797.782567] [093/131] (223s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[797.896848] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc
[804.462687] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.567s)
[804.626128] [094/131] (216s left) gem_eio (create-ext)
[804.846949] Starting subtest: create-ext
[805.173720] Subtest create-ext: SUCCESS (0.326s)
[805.352316] [095/131] (215s left) gem_partial_pwrite_pread (writes-after-reads-uncached)
[805.417378] Subtest writes-after-reads-uncached: SKIP (0.000s)
[805.491986] [096/131] (215s left) sysfs_preempt_timeout (idempotent)
[805.562623] Starting subtest: idempotent
[805.564487] Starting dynamic subtest: vecs0
[805.566459] Dynamic subtest vecs0: SUCCESS (0.000s)
[805.577894] Starting dynamic subtest: ccs0
[805.579596] Dynamic subtest ccs0: SUCCESS (0.000s)
[805.590434] Starting dynamic subtest: vcs0
[805.592019] Dynamic subtest vcs0: SUCCESS (0.000s)
[805.600908] Starting dynamic subtest: bcs0
[805.602446] Dynamic subtest bcs0: SUCCESS (0.000s)
[805.611472] Starting dynamic subtest: rcs0
[805.613006] Dynamic subtest rcs0: SUCCESS (0.000s)
[805.622431] Starting dynamic subtest: vcs1
[805.624169] Dynamic subtest vcs1: SUCCESS (0.000s)
[805.625929] Subtest idempotent: SUCCESS (0.007s)
[805.702618] [097/131] (215s left) gem_busy (semaphore)
[805.890099] Subtest semaphore: SKIP (0.000s)
[806.175807] [098/131] (215s left) sriov_basic (enable-vfs-bind-unbind-each)
[806.236462] Starting subtest: enable-vfs-bind-unbind-each
[806.238877] Starting dynamic subtest: numvfs-1
[806.313296] Dynamic subtest numvfs-1: FAIL (0.006s)
[806.325928] Starting dynamic subtest: numvfs-2
[806.397389] Dynamic subtest numvfs-2: FAIL (0.004s)
[806.407510] Starting dynamic subtest: numvfs-3
[806.477390] Dynamic subtest numvfs-3: FAIL (0.004s)
[806.490045] Starting dynamic subtest: numvfs-4
[806.561986] Dynamic subtest numvfs-4: FAIL (0.003s)
[806.573260] Starting dynamic subtest: numvfs-5
[806.645682] Dynamic subtest numvfs-5: FAIL (0.003s)
[806.657297] Starting dynamic subtest: numvfs-6
[806.728826] Dynamic subtest numvfs-6: FAIL (0.004s)
[806.739861] Starting dynamic subtest: numvfs-7
[806.812762] Dynamic subtest numvfs-7: FAIL (0.004s)
[806.823218] Starting dynamic subtest: numvfs-random
[806.896249] Dynamic subtest numvfs-random: FAIL (0.004s)
[806.898354] Subtest enable-vfs-bind-unbind-each: FAIL (0.036s)
[806.939336] [099/131] (214s left) kms_pm_lpsp (screens-disabled)
[808.039770] Starting subtest: screens-disabled
[808.044118] Subtest screens-disabled: SKIP (0.000s)
[808.136007] [100/131] (213s left) gem_exec_capture (capture-invisible)
[808.206719] Starting subtest: capture-invisible
[808.208613] Starting dynamic subtest: smem0
[808.212596] Dynamic subtest smem0: SKIP (0.000s)
[808.216640] Subtest capture-invisible: SKIP (0.001s)
[808.342143] [101/131] (213s left) gem_lmem_swapping (verify-ccs)
[808.387044] Subtest verify-ccs: SKIP (0.000s)
[808.433654] [102/131] (213s left) kms_joiner (basic-force-ultra-joiner)
[808.533539] Starting subtest: basic-force-ultra-joiner
[808.538074] Subtest basic-force-ultra-joiner: SKIP (0.000s)
[808.625451] [103/131] (213s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-wc)
[808.720715] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-wc
[815.512619] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.794s)
[815.668063] [104/131] (206s left) kms_color (legacy-gamma)
[815.772589] Starting subtest: legacy-gamma
[815.774462] Starting dynamic subtest: pipe-A-eDP-1
[816.482996] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.709s)
[816.494151] Starting dynamic subtest: pipe-B-eDP-1
[817.871617] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.390s)
[817.886987] Starting dynamic subtest: pipe-C-eDP-1
[819.255462] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.383s)
[819.266029] Starting dynamic subtest: pipe-D-eDP-1
[820.580790] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.325s)
[820.583098] Subtest legacy-gamma: SUCCESS (4.811s)
[821.410382] [105/131] (200s left) gem_exec_schedule (wide)
[821.597805] Starting subtest: wide
[821.600293] Starting dynamic subtest: rcs0
[831.749159] Dynamic subtest rcs0: SUCCESS (10.151s)
[831.762412] Starting dynamic subtest: vcs0
[843.501011] Dynamic subtest vcs0: SUCCESS (11.750s)
[843.513469] Starting dynamic subtest: vcs1
[856.065709] Dynamic subtest vcs1: SUCCESS (12.564s)
[856.078790] Starting dynamic subtest: vecs0
[867.564595] Dynamic subtest vecs0: SUCCESS (11.498s)
[867.578644] Starting dynamic subtest: ccs0
[882.345446] Dynamic subtest ccs0: SUCCESS (14.780s)
[882.348422] Subtest wide: SUCCESS (60.749s)
[883.691900] [106/131] (138s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-upscaling)
[883.796574] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-upscaling
[883.798897] Starting dynamic subtest: pipe-A-default-mode
[884.096516] Dynamic subtest pipe-A-default-mode: SKIP (0.297s)
[884.101204] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-upscaling: SKIP (0.299s)
[884.919302] [107/131] (136s left) kms_big_fb (linear-64bpp-rotate-180)
[885.012557] Starting subtest: linear-64bpp-rotate-180
[886.673166] Subtest linear-64bpp-rotate-180: SUCCESS (1.659s)
[886.863870] [108/131] (134s left) gem_ppgtt (blt-vs-render-ctxn)
[886.916270] Starting subtest: blt-vs-render-ctxN
[920.161802] Subtest blt-vs-render-ctxN: SUCCESS (33.240s)
[920.324381] [109/131] (101s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-plflip-blt)
[920.490897] Starting subtest: psr-2p-scndscrn-shrfb-plflip-blt
[920.495448] Subtest psr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[920.618626] [110/131] (101s left) kms_chamelium_frames (dp-frame-dump)
[921.009065] Subtest dp-frame-dump: SKIP (0.000s)
[921.807558] [111/131] (100s left) kms_psr2_su (page_flip-nv12)
[921.875204] Subtest page_flip-NV12: SKIP (0.000s)
[921.951660] [112/131] (100s left) gem_tiling_max_stride
[922.086713] [113/131] ( 99s left) perf_pmu (busy-idle)
[922.124186] Starting subtest: busy-idle
[922.126624] Starting dynamic subtest: rcs0
[922.832269] Dynamic subtest rcs0: SUCCESS (0.708s)
[922.848816] Starting dynamic subtest: bcs0
[923.504177] Dynamic subtest bcs0: SUCCESS (0.669s)
[923.517828] Starting dynamic subtest: vcs0
[924.872645] Dynamic subtest vcs0: SUCCESS (1.364s)
[924.884271] Starting dynamic subtest: vcs1
[926.855292] Dynamic subtest vcs1: SUCCESS (1.980s)
[926.866477] Starting dynamic subtest: vecs0
[928.902578] Dynamic subtest vecs0: SUCCESS (2.044s)
[928.913663] Starting dynamic subtest: ccs0
[929.570525] Dynamic subtest ccs0: SUCCESS (0.667s)
[929.573306] Subtest busy-idle: SUCCESS (7.448s)
[929.715676] [114/131] ( 92s left) dmabuf_sync_file (export-before-signal)
[929.767210] Starting subtest: export-before-signal
[929.769163] Subtest export-before-signal: SUCCESS (0.001s)
[929.848521] [115/131] ( 92s left) gem_exec_reloc (basic-cpu-read)
[929.913002] Subtest basic-cpu-read: SKIP (0.000s)
[929.998149] [116/131] ( 92s left) kms_hdr (bpc-switch)
[930.108972] Starting subtest: bpc-switch
[931.196725] Starting dynamic subtest: pipe-A-eDP-1
[934.695702] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.498s)
[934.698001] Subtest bpc-switch: SUCCESS (4.588s)
[934.861631] [117/131] ( 87s left) perf (i915-ref-count)
[934.973011] Starting subtest: i915-ref-count
[935.053183] Subtest i915-ref-count: SUCCESS (0.079s)
[935.122285] [118/131] ( 87s left) gem_ctx_isolation (nonpriv)
[935.196661] Starting subtest: nonpriv
[935.199269] Starting dynamic subtest: rcs0
[935.483188] Dynamic subtest rcs0: SUCCESS (0.289s)
[935.497893] Starting dynamic subtest: bcs0
[935.756366] Dynamic subtest bcs0: SUCCESS (0.272s)
[935.769283] Starting dynamic subtest: vcs0
[935.997359] Dynamic subtest vcs0: SUCCESS (0.240s)
[936.009993] Starting dynamic subtest: vcs1
[937.096156] Dynamic subtest vcs1: SUCCESS (1.097s)
[937.109329] Starting dynamic subtest: vecs0
[939.152908] Dynamic subtest vecs0: SUCCESS (2.056s)
[939.170286] Starting dynamic subtest: ccs0
[941.168468] Dynamic subtest ccs0: SUCCESS (2.015s)
[941.170801] Subtest nonpriv: SUCCESS (5.975s)
[941.444604] [119/131] ( 80s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[941.515511] Starting subtest: y-tiled-to-vebox-x-tiled
[941.519904] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[941.731651] [120/131] ( 80s left) kms_atomic_transition (plane-use-after-nonblocking-unbind)
[941.828270] Starting subtest: plane-use-after-nonblocking-unbind
[941.830595] Starting dynamic subtest: pipe-A-eDP-1
[941.894300] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.067s)
[942.206564] Starting dynamic subtest: pipe-B-eDP-1
[942.962770] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.768s)
[943.264083] Subtest plane-use-after-nonblocking-unbind: SUCCESS (1.435s)
[944.139854] [121/131] ( 78s left) i915_hangman (engine-error-state-capture)
[944.213952] Starting subtest: engine-error-state-capture
[944.215931] Starting dynamic subtest: rcs0
[947.877763] Dynamic subtest rcs0: SUCCESS (3.663s)
[947.891547] Starting dynamic subtest: bcs0
[953.831051] Dynamic subtest bcs0: SUCCESS (5.949s)
[953.843298] Starting dynamic subtest: vcs0
[959.847273] Dynamic subtest vcs0: SUCCESS (6.014s)
[959.860251] Starting dynamic subtest: vcs1
[965.798067] Dynamic subtest vcs1: SUCCESS (5.947s)
[965.811638] Starting dynamic subtest: vecs0
[970.790344] Dynamic subtest vecs0: SUCCESS (4.989s)
[970.804392] Starting dynamic subtest: ccs0
[975.845888] Dynamic subtest ccs0: SUCCESS (5.052s)
[975.848763] Subtest engine-error-state-capture: SUCCESS (31.630s)
[975.952672] [122/131] ( 46s left) gem_exec_schedule (pi-shared-iova)
[976.169515] Starting subtest: pi-shared-iova
[976.172083] Starting dynamic subtest: rcs0
[976.306721] Dynamic subtest rcs0: SUCCESS (0.136s)
[976.320292] Starting dynamic subtest: vcs0
[976.392276] Dynamic subtest vcs0: SUCCESS (0.085s)
[976.404894] Starting dynamic subtest: vcs1
[976.476686] Dynamic subtest vcs1: SUCCESS (0.084s)
[976.488986] Starting dynamic subtest: vecs0
[976.557772] Dynamic subtest vecs0: SUCCESS (0.080s)
[976.569489] Starting dynamic subtest: ccs0
[976.670131] Dynamic subtest ccs0: SUCCESS (0.111s)
[976.672892] Subtest pi-shared-iova: SUCCESS (0.502s)
[977.984928] [123/131] ( 44s left) syncobj_wait (invalid-reset-illegal-handle)
[978.041125] Starting subtest: invalid-reset-illegal-handle
[978.043477] Subtest invalid-reset-illegal-handle: SUCCESS (0.000s)
[978.184368] [124/131] ( 44s left) syncobj_wait (single-wait-all-for-submit-signaled)
[978.247978] Starting subtest: single-wait-all-for-submit-signaled
[978.250265] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[978.383270] [125/131] ( 44s left) kms_cursor_crc (cursor-sliding-512x170)
[978.677025] Starting subtest: cursor-sliding-512x170
[978.681616] Subtest cursor-sliding-512x170: SKIP (0.001s)
[978.869811] [126/131] ( 43s left) kms_chamelium_hpd (hdmi-hpd-fast)
[979.268001] Subtest hdmi-hpd-fast: SKIP (0.000s)
[980.127725] [127/131] ( 42s left) gem_ctx_persistence (hostile)
[980.193075] Starting subtest: hostile
[990.346782] Subtest hostile: SUCCESS (10.147s)
[990.499587] [128/131] ( 32s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt)
[990.612563] Starting subtest: fbc-rgb565-draw-blt
[995.243001] Subtest fbc-rgb565-draw-blt: SUCCESS (4.636s)
[996.465953] [129/131] ( 26s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite)
[996.562473] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite
[1005.877907] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (9.316s)
[1006.029635] [130/131] ( 16s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[1006.133695] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[1006.137966] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[1006.248790] [131/131] ( 16s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-move)
[1006.348468] Starting subtest: fbc-2p-scndscrn-spr-indfb-move
[1006.352612] Subtest fbc-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[1006.495410] 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.