[59.185199] Initializing watchdogs
[59.185250]   /dev/watchdog0
[59.199035] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[59.203210] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.205319] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.207395] [001/131] (960s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[59.322075] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[59.324037] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s)
[59.435493] [002/131] (960s left) gem_exec_fence (submit3)
[59.603512] Starting subtest: submit3
[59.605307] Subtest submit3: SKIP (0.000s)
[61.006553] [FACT gem_exec_fence (submit3)] new: kernel.kmod_is_loaded.vgem: true
[61.008328] [003/131] (958s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[61.113538] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt
[61.115394] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[61.243917] [004/131] (958s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-wc)
[61.332568] Starting subtest: fbc-rgb101010-draw-mmap-wc
[65.954801] Subtest fbc-rgb101010-draw-mmap-wc: SUCCESS (4.622s)
[67.142339] [005/131] (952s left) gem_exec_balancer (noheartbeat)
[67.549111] Starting subtest: noheartbeat
[67.551743] Subtest noheartbeat: SKIP (0.000s)
[67.804292] [006/131] (952s left) kms_chamelium_color (ctm-blue-to-red)
[67.925499] Subtest ctm-blue-to-red: SKIP (0.000s)
[67.971921] [007/131] (952s left) gem_workarounds (suspend-resume)
[68.060326] Starting subtest: suspend-resume
[84.348791] Subtest suspend-resume: SUCCESS (5.324s)
[90.509343] [008/131] (935s left) kms_plane_scaling (planes-downscale-factor-0-75-unity-scaling)
[90.604832] Starting subtest: planes-downscale-factor-0-75-unity-scaling
[90.605745] Starting dynamic subtest: pipe-A
[90.670600] Dynamic subtest pipe-A: SUCCESS (0.065s)
[90.679602] Starting dynamic subtest: pipe-B
[91.814007] Dynamic subtest pipe-B: SUCCESS (1.143s)
[91.825834] Starting dynamic subtest: pipe-C
[92.925534] Dynamic subtest pipe-C: SUCCESS (1.110s)
[92.934625] Starting dynamic subtest: pipe-D
[94.057931] Dynamic subtest pipe-D: SUCCESS (1.132s)
[94.058876] Subtest planes-downscale-factor-0-75-unity-scaling: SUCCESS (3.453s)
[95.229681] [009/131] (930s left) gem_pxp (create-regular-buffer)
[95.345352] Starting subtest: create-regular-buffer
[95.346402] Subtest create-regular-buffer: SUCCESS (0.000s)
[95.495526] [010/131] (930s left) gem_exec_params (rel-constants-invalid-rel-gen5)
[95.554739] Starting subtest: rel-constants-invalid-rel-gen5
[95.555578] Subtest rel-constants-invalid-rel-gen5: SUCCESS (0.000s)
[95.696987] [011/131] (930s left) gem_exec_nop (basic-series)
[95.762084] Starting subtest: basic-series
[111.856474] Subtest basic-series: SUCCESS (16.092s)
[112.101981] [012/131] (914s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-wc)
[112.191661] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-wc
[112.193389] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[112.316431] [013/131] (913s left) kms_psr (fbc-psr-primary-page-flip)
[112.399707] Starting subtest: fbc-psr-primary-page-flip
[112.400831] Starting dynamic subtest: eDP-1
[112.402495] Dynamic subtest eDP-1: SKIP (0.000s)
[112.404110] Subtest fbc-psr-primary-page-flip: SKIP (0.000s)
[112.487654] [014/131] (913s left) i915_pm_rps (engine-order)
[112.554763] Starting subtest: engine-order
[118.816007] Subtest engine-order: SUCCESS (6.259s)
[118.974567] [015/131] (907s left) gem_softpin (evict-active)
[119.036392] Starting subtest: evict-active
[119.048406] Subtest evict-active: SUCCESS (0.012s)
[119.182164] [016/131] (907s left) syncobj_timeline (wait-delayed-signal)
[119.247589] Starting subtest: wait-delayed-signal
[119.354335] Subtest wait-delayed-signal: SUCCESS (0.103s)
[119.449814] [017/131] (906s left) kms_panel_fitting (atomic-fastset)
[119.546689] Starting subtest: atomic-fastset
[119.547679] Starting dynamic subtest: pipe-A-eDP-1
[119.692201] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.145s)
[119.702273] Starting dynamic subtest: pipe-B-eDP-1
[120.880870] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.188s)
[120.890848] Starting dynamic subtest: pipe-C-eDP-1
[122.069879] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.189s)
[122.078084] Starting dynamic subtest: pipe-D-eDP-1
[123.213694] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.143s)
[123.214559] Subtest atomic-fastset: SUCCESS (3.667s)
[124.379667] [018/131] (901s left) kms_big_fb (x-tiled-16bpp-rotate-270)
[124.485638] Starting subtest: x-tiled-16bpp-rotate-270
[124.506928] Subtest x-tiled-16bpp-rotate-270: SKIP (0.020s)
[124.661601] [019/131] (901s left) api_intel_bb (render-ccs)
[124.725024] Starting subtest: render-ccs
[125.091808] Subtest render-ccs: SUCCESS (0.365s)
[125.255092] [020/131] (901s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc)
[125.348091] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc
[125.349017] Starting dynamic subtest: pipe-A-eDP-1
[125.357658] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[125.365831] Starting dynamic subtest: pipe-B-eDP-1
[125.374267] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[125.381498] Starting dynamic subtest: pipe-C-eDP-1
[125.389645] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[125.396832] Starting dynamic subtest: pipe-D-eDP-1
[125.405219] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[125.406773] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[127.557346] [021/131] (898s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted-signaled)
[127.606390] Starting subtest: multi-wait-all-for-submit-unsubmitted-signaled
[127.712437] Subtest multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.103s)
[127.802681] [022/131] (898s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-move)
[127.902582] Starting subtest: psr-2p-primscrn-cur-indfb-move
[127.904316] Subtest psr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[128.036139] [023/131] (898s left) gem_lmem_swapping (heavy-multi)
[128.073285] Subtest heavy-multi: SKIP (0.000s)
[128.107505] [024/131] (898s left) kms_async_flips (test-cursor)
[128.205265] Starting subtest: test-cursor
[128.206989] Subtest test-cursor: SKIP (0.000s)
[129.383386] [025/131] (897s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-sf)
[134.343614] Starting subtest: fbc-psr2-overlay-plane-move-continuous-exceed-sf
[134.344538] Starting dynamic subtest: pipe-A-eDP-1
[135.149365] Dynamic subtest pipe-A-eDP-1: SKIP (0.804s)
[135.157529] Starting dynamic subtest: pipe-B-eDP-1
[136.277276] Dynamic subtest pipe-B-eDP-1: SKIP (1.126s)
[136.279136] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-sf: SKIP (1.934s)
[137.481221] [026/131] (889s left) kms_plane_multiple (2x-tiling-4)
[137.585039] Starting subtest: 2x-tiling-4
[137.586914] Subtest 2x-tiling-4: SKIP (0.000s)
[137.677923] [027/131] (888s left) gem_ctx_param (invalid-param-set)
[137.734293] Starting subtest: invalid-param-set
[137.735202] Subtest invalid-param-set: SUCCESS (0.000s)
[137.827188] [028/131] (888s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[137.926059] Starting subtest: bad-pixel-format-y-tiled-ccs
[137.928443] Starting dynamic subtest: pipe-A-eDP-1
[137.937063] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[137.944308] Starting dynamic subtest: pipe-B-eDP-1
[137.952754] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[137.959898] Starting dynamic subtest: pipe-C-eDP-1
[137.968324] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[137.975453] Starting dynamic subtest: pipe-D-eDP-1
[137.983692] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[137.985282] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.010s)
[140.236553] [029/131] (886s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[140.345002] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[140.346071] Starting dynamic subtest: pipe-A
[140.362483] Dynamic subtest pipe-A: SKIP (0.015s)
[140.369735] Starting dynamic subtest: pipe-B
[140.390530] Dynamic subtest pipe-B: SKIP (0.028s)
[140.397847] Starting dynamic subtest: pipe-C
[140.418481] Dynamic subtest pipe-C: SKIP (0.028s)
[140.425688] Starting dynamic subtest: pipe-D
[140.446159] Dynamic subtest pipe-D: SKIP (0.027s)
[140.447779] Subtest plane-downscale-factor-0-5-with-modifiers: SKIP (0.099s)
[140.574241] [030/131] (886s left) syncobj_timeline (multi-wait-all-available-submitted-signaled)
[140.631158] Starting subtest: multi-wait-all-available-submitted-signaled
[140.632044] Subtest multi-wait-all-available-submitted-signaled: SUCCESS (0.000s)
[140.772853] [031/131] (886s left) perf (missing-sample-flags)
[140.902841] Starting subtest: missing-sample-flags
[140.903689] Subtest missing-sample-flags: SUCCESS (0.000s)
[140.936612] [032/131] (885s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-pwrite)
[141.036242] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-pwrite
[147.290363] Subtest fbc-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (6.257s)
[148.438338] [033/131] (878s left) kms_pm_rpm (system-suspend-idle)
[150.011402] Starting subtest: system-suspend-idle
[169.880327] Subtest system-suspend-idle: SUCCESS (5.044s)
[174.285207] [034/131] (856s left) kms_vblank (wait-forked)
[174.384300] Starting subtest: wait-forked
[174.385293] Starting dynamic subtest: pipe-A-eDP-1
[176.205637] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.819s)
[176.215671] Starting dynamic subtest: pipe-D-eDP-1
[178.758821] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.550s)
[178.759973] Subtest wait-forked: SUCCESS (4.373s)
[179.635384] [035/131] (851s left) kms_big_fb (y-tiled-32bpp-rotate-0)
[179.730878] Starting subtest: y-tiled-32bpp-rotate-0
[179.732647] Subtest y-tiled-32bpp-rotate-0: SKIP (0.000s)
[179.839744] [036/131] (851s left) kms_pipe_crc_basic (read-crc)
[179.942863] Starting subtest: read-crc
[179.943782] Starting dynamic subtest: pipe-A-eDP-1
[180.641861] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.698s)
[180.649484] Starting dynamic subtest: pipe-B-eDP-1
[182.003726] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.362s)
[182.010951] Starting dynamic subtest: pipe-C-eDP-1
[183.299187] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.294s)
[183.310580] Starting dynamic subtest: pipe-D-eDP-1
[184.631260] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.331s)
[184.632110] Subtest read-crc: SUCCESS (4.690s)
[185.492705] [037/131] (845s left) kms_cursor_legacy (single-move)
[185.619751] Starting subtest: single-move
[185.620668] Starting dynamic subtest: pipe-A
[191.003101] Dynamic subtest pipe-A: SUCCESS (5.379s)
[191.013565] Starting dynamic subtest: pipe-B
[196.386820] Dynamic subtest pipe-B: SUCCESS (5.380s)
[196.396903] Starting dynamic subtest: pipe-C
[201.768283] Dynamic subtest pipe-C: SUCCESS (5.380s)
[201.778445] Starting dynamic subtest: pipe-D
[207.148002] Dynamic subtest pipe-D: SUCCESS (5.380s)
[207.160069] Starting dynamic subtest: all-pipes
[212.537728] Dynamic subtest all-pipes: SUCCESS (5.387s)
[212.538859] Subtest single-move: SUCCESS (26.914s)
[212.699268] [038/131] (818s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-sf)
[217.755804] Starting subtest: pr-overlay-plane-move-continuous-exceed-sf
[217.758660] Subtest pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.002s)
[218.614938] [039/131] (812s left) gem_unref_active_buffers
[218.840807] [040/131] (812s left) kms_psr (fbc-psr2-primary-mmap-gtt)
[218.927555] Starting subtest: fbc-psr2-primary-mmap-gtt
[218.928548] Starting dynamic subtest: eDP-1
[218.930328] Dynamic subtest eDP-1: SKIP (0.000s)
[218.932090] Subtest fbc-psr2-primary-mmap-gtt: SKIP (0.001s)
[219.024310] [041/131] (812s left) syncobj_wait (invalid-signal-zero-handles)
[219.076270] Starting subtest: invalid-signal-zero-handles
[219.077088] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[219.205417] [042/131] (811s left) gen9_exec_parse (bb-oversize)
[219.263556] Subtest bb-oversize: SKIP (0.000s)
[219.314369] [043/131] (811s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted)
[219.363746] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted
[219.474929] Subtest etime-multi-wait-all-for-submit-available-unsubmitted: SUCCESS (0.108s)
[219.567327] [044/131] (811s left) gem_exec_reloc (basic-write-cpu-noreloc)
[219.629248] Subtest basic-write-cpu-noreloc: SKIP (0.000s)
[219.706788] [045/131] (811s left) gem_mmap_offset (bad-flags)
[219.756791] Starting subtest: bad-flags
[219.757627] Subtest bad-flags: SUCCESS (0.000s)
[219.875229] [046/131] (811s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[219.982206] Starting subtest: cursor-vs-flip-legacy
[249.287557] Subtest cursor-vs-flip-legacy: SUCCESS (29.304s)
[250.146748] [047/131] (781s left) kms_rotation_crc (sprite-rotation-90-pos-100-0)
[250.244818] Starting subtest: sprite-rotation-90-pos-100-0
[250.246598] Subtest sprite-rotation-90-pos-100-0: SKIP (0.001s)
[250.335615] [048/131] (781s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[250.430446] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip
[251.385813] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip: SUCCESS (0.956s)
[251.555082] [049/131] (779s left) gem_create (busy-create)
[251.598845] Starting subtest: busy-create
[251.599764] Starting dynamic subtest: smem0
[284.720252] Dynamic subtest smem0: SUCCESS (33.117s)
[284.721461] Subtest busy-create: SUCCESS (33.119s)
[284.922583] [050/131] (746s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-render)
[285.026123] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-render
[289.208881] Subtest fbc-1p-offscreen-pri-indfb-draw-render: SUCCESS (4.182s)
[290.347073] [051/131] (741s left) perf (invalid-open-flags)
[290.450263] Starting subtest: invalid-open-flags
[290.451372] Subtest invalid-open-flags: SUCCESS (0.000s)
[290.484701] [052/131] (740s left) perf (oa-formats)
[290.580046] Starting subtest: oa-formats
[290.581045] Starting dynamic subtest: 0-rcs0
[290.593520] Dynamic subtest 0-rcs0: SUCCESS (0.015s)
[290.602439] Starting dynamic subtest: 1-vcs0
[290.616185] Dynamic subtest 1-vcs0: SUCCESS (0.022s)
[290.617113] Subtest oa-formats: SUCCESS (0.038s)
[290.650157] [053/131] (740s left) kms_big_fb (4-tiled-8bpp-rotate-270)
[290.753911] Starting subtest: 4-tiled-8bpp-rotate-270
[290.775301] Subtest 4-tiled-8bpp-rotate-270: SKIP (0.021s)
[290.918525] [054/131] (740s left) kms_cursor_crc (cursor-onscreen-64x21)
[291.116101] Starting subtest: cursor-onscreen-64x21
[291.155072] Subtest cursor-onscreen-64x21: SKIP (0.037s)
[291.408614] [055/131] (740s left) kms_rotation_crc (primary-4-tiled-reflect-x-180)
[291.493748] Starting subtest: primary-4-tiled-reflect-x-180
[298.481635] Subtest primary-4-tiled-reflect-x-180: SUCCESS (6.989s)
[299.693223] [056/131] (731s left) kms_cursor_crc (cursor-onscreen-256x256)
[299.910361] Starting subtest: cursor-onscreen-256x256
[299.913748] Starting dynamic subtest: pipe-A-eDP-1
[302.012963] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.099s)
[302.040836] Starting dynamic subtest: pipe-D-eDP-1
[304.770895] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.736s)
[304.771762] Subtest cursor-onscreen-256x256: SUCCESS (4.860s)
[305.587180] [057/131] (726s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-msflip-blt)
[305.683451] Starting subtest: fbcpsr-1p-primscrn-shrfb-msflip-blt
[313.006832] Subtest fbcpsr-1p-primscrn-shrfb-msflip-blt: SUCCESS (7.324s)
[313.205505] [058/131] (718s left) kms_async_flips (async-flip-with-page-flip-events-tiled)
[313.307275] Starting subtest: async-flip-with-page-flip-events-tiled
[313.698177] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs
[316.466638] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: SUCCESS (2.766s)
[316.474311] Starting dynamic subtest: pipe-A-eDP-1-4
[318.653348] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.185s)
[318.661285] Starting dynamic subtest: pipe-A-eDP-1-4
[320.663470] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.009s)
[320.673699] Starting dynamic subtest: pipe-A-eDP-1-x
[322.854352] Dynamic subtest pipe-A-eDP-1-x: SUCCESS (2.190s)
[323.219967] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs
[325.993045] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: SUCCESS (2.779s)
[326.001312] Starting dynamic subtest: pipe-B-eDP-1-4
[328.181181] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.185s)
[328.198516] Starting dynamic subtest: pipe-B-eDP-1-4
[330.189515] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.007s)
[330.197278] Starting dynamic subtest: pipe-B-eDP-1-x
[332.379254] Dynamic subtest pipe-B-eDP-1-x: SUCCESS (2.189s)
[332.763434] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs
[335.524707] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.766s)
[335.532978] Starting dynamic subtest: pipe-C-eDP-1-4
[337.710287] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.185s)
[337.717915] Starting dynamic subtest: pipe-C-eDP-1-4
[339.719457] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.009s)
[339.727969] Starting dynamic subtest: pipe-C-eDP-1-x
[341.899316] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.177s)
[342.259858] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs
[345.055054] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.800s)
[345.064447] Starting dynamic subtest: pipe-D-eDP-1-4
[347.230009] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.174s)
[347.238072] Starting dynamic subtest: pipe-D-eDP-1-4
[349.241112] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.009s)
[349.249911] Starting dynamic subtest: pipe-D-eDP-1-x
[351.417455] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.177s)
[351.418429] Subtest async-flip-with-page-flip-events-tiled: SUCCESS (38.110s)
[352.671325] [059/131] (679s left) perf (polling)
[352.770770] Starting subtest: polling
[352.772069] Starting dynamic subtest: 0-rcs0
[362.785466] Dynamic subtest 0-rcs0: SUCCESS (10.012s)
[362.796749] Starting dynamic subtest: 1-vecs0
[372.809265] Dynamic subtest 1-vecs0: SUCCESS (10.023s)
[372.810720] Subtest polling: SUCCESS (20.038s)
[372.853130] [060/131] (658s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[372.950018] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[372.951796] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[373.059892] [061/131] (658s left) syncobj_timeline (reset-signaled)
[373.122454] Starting subtest: reset-signaled
[373.123517] Subtest reset-signaled: SUCCESS (0.000s)
[373.247370] [062/131] (658s left) kms_rmfb (rmfb-ioctl)
[373.338878] Starting subtest: rmfb-ioctl
[373.339870] Starting dynamic subtest: pipe-A-eDP-1
[373.444644] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.106s)
[373.455921] Starting dynamic subtest: pipe-B-eDP-1
[374.594765] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.147s)
[374.606141] Starting dynamic subtest: pipe-C-eDP-1
[375.808034] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.210s)
[375.817922] Starting dynamic subtest: pipe-D-eDP-1
[376.938447] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.129s)
[376.940818] Subtest rmfb-ioctl: SUCCESS (3.602s)
[378.174313] [063/131] (653s left) gem_userptr_blits (create-destroy-sync)
[378.237954] Starting subtest: create-destroy-sync
[383.268264] Subtest create-destroy-sync: SUCCESS (5.008s)
[383.419288] [064/131] (648s left) gem_exec_reloc (basic-gtt-cpu-active)
[383.480664] Subtest basic-gtt-cpu-active: SKIP (0.000s)
[383.560620] [065/131] (648s left) api_intel_allocator (execbuf-with-allocator)
[383.616189] Starting subtest: execbuf-with-allocator
[383.621250] Subtest execbuf-with-allocator: SUCCESS (0.006s)
[383.738008] [066/131] (648s left) kms_lease (cursor-implicit-plane)
[383.832901] Starting subtest: cursor-implicit-plane
[383.833810] Starting dynamic subtest: pipe-A-eDP-1
[384.288287] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.455s)
[384.297961] Starting dynamic subtest: pipe-B-eDP-1
[385.375424] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.085s)
[385.383560] Starting dynamic subtest: pipe-C-eDP-1
[386.508077] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.131s)
[386.516311] Starting dynamic subtest: pipe-D-eDP-1
[387.635361] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.126s)
[387.636289] Subtest cursor-implicit-plane: SUCCESS (3.803s)
[388.501400] [067/131] (643s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions-varying-size)
[388.628431] Starting subtest: cursorA-vs-flipB-atomic-transitions-varying-size
[388.630245] Subtest cursorA-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[388.792339] [068/131] (643s left) syncobj_wait (invalid-signal-illegal-handle)
[388.852032] Starting subtest: invalid-signal-illegal-handle
[388.853134] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[388.977373] [069/131] (643s left) kms_psr (psr-primary-blt)
[389.069836] Starting subtest: psr-primary-blt
[389.070768] Starting dynamic subtest: eDP-1
[390.770758] Dynamic subtest eDP-1: SUCCESS (1.700s)
[390.771646] Subtest psr-primary-blt: SUCCESS (1.702s)
[391.659490] [070/131] (640s left) kms_ccs (crc-sprite-planes-basic-y-tiled-gen12-rc-ccs)
[391.768158] Starting subtest: crc-sprite-planes-basic-y-tiled-gen12-rc-ccs
[391.769099] Starting dynamic subtest: pipe-A-eDP-1
[391.790595] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[391.798683] Starting dynamic subtest: pipe-B-eDP-1
[391.819765] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[391.827379] Starting dynamic subtest: pipe-C-eDP-1
[391.848058] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[391.855508] Starting dynamic subtest: pipe-D-eDP-1
[391.875861] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[391.877417] Subtest crc-sprite-planes-basic-y-tiled-gen12-rc-ccs: SKIP (0.009s)
[394.020536] [071/131] (638s left) kms_psr (pr-primary-blt)
[394.127740] Starting subtest: pr-primary-blt
[394.129400] Subtest pr-primary-blt: SKIP (0.000s)
[394.221871] [072/131] (637s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-move)
[394.314452] Starting subtest: psr-2p-primscrn-spr-indfb-move
[394.316031] Subtest psr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[394.444586] [073/131] (637s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[394.542302] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[394.543831] Starting dynamic subtest: pipe-A-default-mode
[395.669106] Dynamic subtest pipe-A-default-mode: SKIP (1.124s)
[395.670892] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SKIP (1.126s)
[395.837653] [074/131] (636s left) gem_exec_schedule (u-semaphore-noskip)
[395.907558] Starting subtest: u-semaphore-noskip
[396.305242] Subtest u-semaphore-noskip: SUCCESS (0.395s)
[396.664549] [075/131] (635s left) i915_drm_fdinfo (memory-info-idle)
[396.698637] Starting subtest: memory-info-idle
[396.699554] Starting dynamic subtest: smem0
[396.764862] Dynamic subtest smem0: SUCCESS (0.065s)
[396.766322] Subtest memory-info-idle: SUCCESS (0.066s)
[396.853265] [076/131] (635s left) i915_selftest (live)
[400.368892] Starting subtest: live
[400.370226] Starting dynamic subtest: sanitycheck
[404.691365] Dynamic subtest sanitycheck: SUCCESS (4.322s)
[404.700007] Starting dynamic subtest: uncore
[409.101239] Dynamic subtest uncore: SUCCESS (4.408s)
[409.109583] Starting dynamic subtest: workarounds
[425.731991] Dynamic subtest workarounds: FAIL (16.420s)
[425.738009] Starting dynamic subtest: gt_engines
[428.681824] Dynamic subtest gt_engines: SUCCESS (3.156s)
[428.690438] Starting dynamic subtest: gt_timelines
[445.719545] Dynamic subtest gt_timelines: SUCCESS (17.037s)
[445.729296] Starting dynamic subtest: gt_contexts
[450.111478] Dynamic subtest gt_contexts: SUCCESS (4.388s)
[450.121045] Starting dynamic subtest: gt_lrc
[456.323788] Dynamic subtest gt_lrc: SUCCESS (6.210s)
[456.332126] Starting dynamic subtest: gt_mocs
[459.401653] Dynamic subtest gt_mocs: SUCCESS (3.075s)
[459.409789] Starting dynamic subtest: gt_pm
[466.232239] Dynamic subtest gt_pm: SUCCESS (6.830s)
[466.240388] Starting dynamic subtest: gt_heartbeat
[470.585335] Dynamic subtest gt_heartbeat: SUCCESS (4.351s)
[470.594059] Starting dynamic subtest: gt_tlb
[479.955436] Dynamic subtest gt_tlb: SUCCESS (9.366s)
[479.963847] Starting dynamic subtest: requests
[491.819269] Dynamic subtest requests: SUCCESS (11.861s)
[491.827660] Starting dynamic subtest: migrate
[499.893418] Dynamic subtest migrate: SUCCESS (8.072s)
[499.902641] Starting dynamic subtest: active
[504.244898] Dynamic subtest active: SUCCESS (4.351s)
[504.254984] Starting dynamic subtest: objects
[509.076905] Dynamic subtest objects: SUCCESS (4.829s)
[509.086830] Starting dynamic subtest: mman
[513.974086] Dynamic subtest mman: SUCCESS (4.892s)
[513.982161] Starting dynamic subtest: dmabuf
[518.338449] Dynamic subtest dmabuf: SUCCESS (4.364s)
[518.348881] Starting dynamic subtest: vma
[522.728230] Dynamic subtest vma: SUCCESS (4.385s)
[522.737100] Starting dynamic subtest: coherency
[526.548158] Dynamic subtest coherency: SUCCESS (3.818s)
[526.556447] Starting dynamic subtest: gtt
[538.398798] Dynamic subtest gtt: SUCCESS (11.848s)
[538.407351] Starting dynamic subtest: gem
[546.537172] Dynamic subtest gem: SUCCESS (8.137s)
[546.546781] Starting dynamic subtest: evict
[550.555849] Dynamic subtest evict: SUCCESS (4.016s)
[550.564077] Starting dynamic subtest: hugepages
[559.606738] Dynamic subtest hugepages: SUCCESS (9.048s)
[559.620302] Starting dynamic subtest: gem_contexts
[586.777687] Dynamic subtest gem_contexts: SUCCESS (27.169s)
[586.790788] Starting dynamic subtest: client
[591.160216] Dynamic subtest client: SUCCESS (4.381s)
[591.169160] Starting dynamic subtest: gem_migrate
[595.529299] Dynamic subtest gem_migrate: SUCCESS (4.367s)
[595.538165] Starting dynamic subtest: reset
[599.926936] Dynamic subtest reset: SUCCESS (4.397s)
[599.936572] Starting dynamic subtest: memory_region
[604.322466] Dynamic subtest memory_region: SUCCESS (4.392s)
[604.330859] Starting dynamic subtest: hangcheck
[617.865180] Dynamic subtest hangcheck: SUCCESS (13.540s)
[617.879284] Starting dynamic subtest: execlists
[622.259731] Dynamic subtest execlists: SUCCESS (4.392s)
[622.268435] Starting dynamic subtest: ring_submission
[626.612973] Dynamic subtest ring_submission: SUCCESS (4.351s)
[626.621884] Starting dynamic subtest: perf
[630.953684] Dynamic subtest perf: SUCCESS (4.338s)
[630.961861] Starting dynamic subtest: slpc
[645.146200] Dynamic subtest slpc: SUCCESS (14.190s)
[645.154263] Starting dynamic subtest: guc
[649.064439] Dynamic subtest guc: SUCCESS (3.916s)
[649.073460] Starting dynamic subtest: guc_multi_lrc
[653.391520] Dynamic subtest guc_multi_lrc: SUCCESS (4.327s)
[653.398746] Starting dynamic subtest: guc_hang
[658.329627] Dynamic subtest guc_hang: SUCCESS (4.934s)
[658.338989] Starting dynamic subtest: late_gt_pm
[662.780643] Dynamic subtest late_gt_pm: SUCCESS (4.449s)
[662.781765] Subtest live: FAIL (262.403s)
[662.819348] [FACT i915_selftest (live)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[662.820044] [FACT i915_selftest (live)] deleted: kernel.kmod_is_loaded.i915: true
[662.821894] [077/131] (369s left) gem_userptr_blits (nohangcheck)
[664.302804] Starting subtest: nohangcheck
[664.585886] Subtest nohangcheck: SUCCESS (0.280s)
[664.683251] [FACT gem_userptr_blits (nohangcheck)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[664.684721] [FACT gem_userptr_blits (nohangcheck)] new: kernel.kmod_is_loaded.i915: true
[664.687197] [078/131] (367s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs)
[664.774052] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs
[664.775091] Starting dynamic subtest: pipe-A-eDP-1
[665.591764] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.815s)
[665.599756] Starting dynamic subtest: pipe-B-eDP-1
[667.125830] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.533s)
[667.133319] Starting dynamic subtest: pipe-C-eDP-1
[668.631888] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.504s)
[668.639482] Starting dynamic subtest: pipe-D-eDP-1
[670.173466] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.540s)
[670.174450] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs: SUCCESS (5.398s)
[671.367952] [079/131] (361s left) gem_softpin (allocator-nopin)
[671.438600] Starting subtest: allocator-nopin
[671.526919] Subtest allocator-nopin: SUCCESS (0.087s)
[671.689648] [080/131] (360s left) kms_draw_crc (draw-method-pwrite)
[671.780451] Starting subtest: draw-method-pwrite
[671.781330] Starting dynamic subtest: xrgb8888-untiled
[672.448523] Dynamic subtest xrgb8888-untiled: SUCCESS (0.668s)
[672.456331] Starting dynamic subtest: rgb565-untiled
[672.970525] Dynamic subtest rgb565-untiled: SUCCESS (0.521s)
[672.978500] Starting dynamic subtest: xrgb2101010-untiled
[673.581772] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.610s)
[673.589937] Starting dynamic subtest: xbgr16161616f-untiled
[674.404067] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.821s)
[674.412113] Starting dynamic subtest: xrgb8888-xtiled
[676.582101] Dynamic subtest xrgb8888-xtiled: SUCCESS (2.177s)
[676.589959] Starting dynamic subtest: rgb565-xtiled
[678.004342] Dynamic subtest rgb565-xtiled: SUCCESS (1.421s)
[678.012287] Starting dynamic subtest: xrgb2101010-xtiled
[680.193260] Dynamic subtest xrgb2101010-xtiled: SUCCESS (2.188s)
[680.201271] Starting dynamic subtest: xbgr16161616f-xtiled
[683.959966] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (3.766s)
[683.967982] Starting dynamic subtest: xrgb8888-4tiled
[685.282332] Dynamic subtest xrgb8888-4tiled: SUCCESS (1.322s)
[685.290430] Starting dynamic subtest: rgb565-4tiled
[685.982328] Dynamic subtest rgb565-4tiled: SUCCESS (0.699s)
[685.990514] Starting dynamic subtest: xrgb2101010-4tiled
[687.338125] Dynamic subtest xrgb2101010-4tiled: SUCCESS (1.355s)
[687.346154] Starting dynamic subtest: xbgr16161616f-4tiled
[690.727200] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (3.388s)
[690.728068] Subtest draw-method-pwrite: SUCCESS (18.946s)
[690.861878] [081/131] (341s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[690.965610] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip
[690.967238] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[691.101946] [082/131] (341s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-plflip-blt)
[691.192234] Starting subtest: fbc-2p-primscrn-indfb-plflip-blt
[691.194011] Subtest fbc-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[691.325755] [083/131] (341s left) syncobj_timeline (host-signal-points)
[691.381802] Starting subtest: host-signal-points
[691.385583] Subtest host-signal-points: SUCCESS (0.004s)
[691.520138] [084/131] (341s left) gem_mmap_gtt (basic-small-bo)
[691.580408] Subtest basic-small-bo: SKIP (0.000s)
[691.645244] [085/131] (340s left) perf_pmu (semaphore-wait-idle)
[691.678085] Starting subtest: semaphore-wait-idle
[691.679001] Starting dynamic subtest: rcs0
[692.197696] Dynamic subtest rcs0: SUCCESS (0.515s)
[692.206830] Starting dynamic subtest: bcs0
[692.710465] Dynamic subtest bcs0: SUCCESS (0.510s)
[692.721039] Starting dynamic subtest: vcs0
[693.223250] Dynamic subtest vcs0: SUCCESS (0.510s)
[693.233904] Starting dynamic subtest: vcs1
[693.734297] Dynamic subtest vcs1: SUCCESS (0.510s)
[693.745945] Starting dynamic subtest: vecs0
[694.249462] Dynamic subtest vecs0: SUCCESS (0.512s)
[694.260711] Starting dynamic subtest: ccs0
[694.766265] Dynamic subtest ccs0: SUCCESS (0.513s)
[694.767990] Subtest semaphore-wait-idle: SUCCESS (3.088s)
[694.922781] [086/131] (337s left) kms_addfb_basic (no-handle)
[694.973162] Starting subtest: no-handle
[694.974074] Subtest no-handle: SUCCESS (0.000s)
[695.122440] [087/131] (337s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-pwrite)
[695.225970] Starting subtest: fbcpsr-rgb101010-draw-pwrite
[702.207683] Subtest fbcpsr-rgb101010-draw-pwrite: SUCCESS (6.982s)
[702.327976] [088/131] (330s left) gem_render_copy (yf-tiled-ccs-to-linear)
[702.398999] Starting subtest: yf-tiled-ccs-to-linear
[702.400850] Subtest yf-tiled-ccs-to-linear: SKIP (0.000s)
[702.598172] [089/131] (330s left) kms_flip (wf_vblank-ts-check)
[702.682744] Starting subtest: wf_vblank-ts-check
[702.684860] Starting dynamic subtest: A-eDP1
[711.578225] Dynamic subtest A-eDP1: SUCCESS (8.894s)
[711.590222] Starting dynamic subtest: B-eDP1
[720.504716] Dynamic subtest B-eDP1: SUCCESS (8.921s)
[720.516139] Starting dynamic subtest: C-eDP1
[729.423429] Dynamic subtest C-eDP1: SUCCESS (8.920s)
[729.432895] Starting dynamic subtest: D-eDP1
[738.360998] Dynamic subtest D-eDP1: SUCCESS (8.936s)
[738.365951] Subtest wf_vblank-ts-check: SUCCESS (35.682s)
[739.608832] [090/131] (293s left) gem_exec_schedule (deep)
[739.781262] Starting subtest: deep
[739.782280] Starting dynamic subtest: rcs0
[744.556854] Dynamic subtest rcs0: SKIP (4.774s)
[744.566964] Starting dynamic subtest: vcs0
[754.823827] Dynamic subtest vcs0: SUCCESS (10.266s)
[754.835476] Starting dynamic subtest: vcs1
[766.995933] Dynamic subtest vcs1: SUCCESS (12.168s)
[767.007383] Starting dynamic subtest: vecs0
[778.389326] Dynamic subtest vecs0: SUCCESS (11.392s)
[778.401343] Starting dynamic subtest: ccs0
[791.552252] Dynamic subtest ccs0: SUCCESS (13.160s)
[791.553919] Subtest deep: SUCCESS (51.767s)
[793.308444] [091/131] (239s left) kms_sequence (queue-idle)
[793.407356] Starting subtest: queue-idle
[793.408408] Starting dynamic subtest: pipe-A-eDP-1
[795.209796] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.801s)
[795.217993] Starting dynamic subtest: pipe-B-eDP-1
[797.635774] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.424s)
[797.643976] Starting dynamic subtest: pipe-C-eDP-1
[800.084346] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.448s)
[800.093685] Starting dynamic subtest: pipe-D-eDP-1
[802.498780] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.413s)
[802.499668] Subtest queue-idle: SUCCESS (9.091s)
[803.333041] [092/131] (229s left) gem_gtt_cpu_tlb
[803.463086] [093/131] (229s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[803.558096] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc
[810.003495] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.446s)
[810.147745] [094/131] (222s left) gem_eio (create-ext)
[810.379484] Starting subtest: create-ext
[810.699465] Subtest create-ext: SUCCESS (0.321s)
[810.873927] [095/131] (222s left) gem_partial_pwrite_pread (writes-after-reads-uncached)
[810.941155] Subtest writes-after-reads-uncached: SKIP (0.000s)
[811.023418] [096/131] (221s left) sysfs_preempt_timeout (idempotent)
[811.079023] Starting subtest: idempotent
[811.080142] Starting dynamic subtest: vecs0
[811.081204] Dynamic subtest vecs0: SUCCESS (0.001s)
[811.091174] Starting dynamic subtest: ccs0
[811.092018] Dynamic subtest ccs0: SUCCESS (0.001s)
[811.100614] Starting dynamic subtest: vcs0
[811.101428] Dynamic subtest vcs0: SUCCESS (0.001s)
[811.109799] Starting dynamic subtest: bcs0
[811.110626] Dynamic subtest bcs0: SUCCESS (0.000s)
[811.118642] Starting dynamic subtest: rcs0
[811.119479] Dynamic subtest rcs0: SUCCESS (0.000s)
[811.127516] Starting dynamic subtest: vcs1
[811.128369] Dynamic subtest vcs1: SUCCESS (0.000s)
[811.129250] Subtest idempotent: SUCCESS (0.010s)
[811.232784] [097/131] (221s left) gem_busy (semaphore)
[811.420249] Subtest semaphore: SKIP (0.000s)
[811.679214] [098/131] (221s left) sriov_basic (enable-vfs-bind-unbind-each)
[811.728000] Starting subtest: enable-vfs-bind-unbind-each
[811.728953] Starting dynamic subtest: numvfs-1
[811.755706] Dynamic subtest numvfs-1: FAIL (0.007s)
[811.765746] Starting dynamic subtest: numvfs-2
[811.792110] Dynamic subtest numvfs-2: FAIL (0.004s)
[811.800709] Starting dynamic subtest: numvfs-3
[811.827196] Dynamic subtest numvfs-3: FAIL (0.004s)
[811.835624] Starting dynamic subtest: numvfs-4
[811.862270] Dynamic subtest numvfs-4: FAIL (0.006s)
[811.870967] Starting dynamic subtest: numvfs-5
[811.900461] Dynamic subtest numvfs-5: FAIL (0.004s)
[811.909523] Starting dynamic subtest: numvfs-6
[811.936869] Dynamic subtest numvfs-6: FAIL (0.004s)
[811.945027] Starting dynamic subtest: numvfs-7
[811.972382] Dynamic subtest numvfs-7: FAIL (0.004s)
[811.980526] Starting dynamic subtest: numvfs-random
[812.006999] Dynamic subtest numvfs-random: FAIL (0.003s)
[812.007836] Subtest enable-vfs-bind-unbind-each: FAIL (0.042s)
[812.036028] [099/131] (221s left) kms_pm_lpsp (screens-disabled)
[813.135754] Starting subtest: screens-disabled
[813.137326] Subtest screens-disabled: SKIP (0.000s)
[813.227447] [100/131] (219s left) gem_exec_capture (capture-invisible)
[813.282838] Starting subtest: capture-invisible
[813.283806] Starting dynamic subtest: smem0
[813.285775] Dynamic subtest smem0: SKIP (0.000s)
[813.287515] Subtest capture-invisible: SKIP (0.001s)
[813.413859] [101/131] (219s left) gem_lmem_swapping (verify-ccs)
[813.447575] Subtest verify-ccs: SKIP (0.000s)
[813.489234] [102/131] (219s left) kms_joiner (basic-force-ultra-joiner)
[813.576404] Starting subtest: basic-force-ultra-joiner
[813.578052] Subtest basic-force-ultra-joiner: SKIP (0.000s)
[813.666821] [103/131] (219s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-wc)
[813.758695] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-wc
[820.239928] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.481s)
[820.385019] [104/131] (212s left) kms_color (legacy-gamma)
[820.488500] Starting subtest: legacy-gamma
[820.489336] Starting dynamic subtest: pipe-A-eDP-1
[821.194709] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.705s)
[821.203605] Starting dynamic subtest: pipe-B-eDP-1
[822.540929] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.346s)
[822.549793] Starting dynamic subtest: pipe-C-eDP-1
[823.938963] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.396s)
[823.947933] Starting dynamic subtest: pipe-D-eDP-1
[825.291565] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.352s)
[825.292436] Subtest legacy-gamma: SUCCESS (4.803s)
[826.112894] [105/131] (207s left) gem_exec_schedule (wide)
[826.301647] Starting subtest: wide
[826.302620] Starting dynamic subtest: rcs0
[837.954616] Dynamic subtest rcs0: SUCCESS (11.652s)
[837.967302] Starting dynamic subtest: vcs0
[849.451559] Dynamic subtest vcs0: SUCCESS (11.498s)
[849.462559] Starting dynamic subtest: vcs1
[861.667807] Dynamic subtest vcs1: SUCCESS (12.212s)
[861.676846] Starting dynamic subtest: vecs0
[873.514867] Dynamic subtest vecs0: SUCCESS (11.846s)
[873.526080] Starting dynamic subtest: ccs0
[887.306611] Dynamic subtest ccs0: SUCCESS (13.789s)
[887.308116] Subtest wide: SUCCESS (61.003s)
[889.507866] [106/131] (143s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-upscaling)
[889.601797] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-upscaling
[889.602673] Starting dynamic subtest: pipe-A-default-mode
[889.897238] Dynamic subtest pipe-A-default-mode: SKIP (0.294s)
[889.898903] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-upscaling: SKIP (0.296s)
[890.742838] [107/131] (142s left) kms_big_fb (linear-64bpp-rotate-180)
[890.854257] Starting subtest: linear-64bpp-rotate-180
[892.356226] Subtest linear-64bpp-rotate-180: SUCCESS (1.502s)
[892.580779] [108/131] (140s left) gem_ppgtt (blt-vs-render-ctxn)
[892.636904] Starting subtest: blt-vs-render-ctxN
[925.909415] Subtest blt-vs-render-ctxN: SUCCESS (33.268s)
[926.074795] [109/131] (107s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-plflip-blt)
[926.210906] Starting subtest: psr-2p-scndscrn-shrfb-plflip-blt
[926.212916] Subtest psr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[926.354002] [110/131] (107s left) kms_chamelium_frames (dp-frame-dump)
[926.742889] Subtest dp-frame-dump: SKIP (0.000s)
[927.550143] [111/131] (105s left) kms_psr2_su (page_flip-nv12)
[927.617550] Subtest page_flip-NV12: SKIP (0.000s)
[927.682579] [112/131] (105s left) gem_tiling_max_stride
[927.815183] [113/131] (105s left) perf_pmu (busy-idle)
[927.849126] Starting subtest: busy-idle
[927.850200] Starting dynamic subtest: rcs0
[928.565953] Dynamic subtest rcs0: SUCCESS (0.716s)
[928.579144] Starting dynamic subtest: bcs0
[929.243636] Dynamic subtest bcs0: SUCCESS (0.675s)
[929.257160] Starting dynamic subtest: vcs0
[930.710996] Dynamic subtest vcs0: SUCCESS (1.463s)
[930.723372] Starting dynamic subtest: vcs1
[932.694870] Dynamic subtest vcs1: SUCCESS (1.981s)
[932.707245] Starting dynamic subtest: vecs0
[934.676753] Dynamic subtest vecs0: SUCCESS (1.980s)
[934.688760] Starting dynamic subtest: ccs0
[935.356718] Dynamic subtest ccs0: SUCCESS (0.678s)
[935.357886] Subtest busy-idle: SUCCESS (7.508s)
[935.480147] [114/131] ( 98s left) dmabuf_sync_file (export-before-signal)
[935.522324] Starting subtest: export-before-signal
[935.523425] Subtest export-before-signal: SUCCESS (0.001s)
[935.600295] [115/131] ( 98s left) gem_exec_reloc (basic-cpu-read)
[935.658855] Subtest basic-cpu-read: SKIP (0.000s)
[935.720682] [116/131] ( 97s left) kms_hdr (bpc-switch)
[935.816781] Starting subtest: bpc-switch
[936.925479] Starting dynamic subtest: pipe-A-eDP-1
[940.425758] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.501s)
[940.426657] Subtest bpc-switch: SUCCESS (4.610s)
[940.580108] [117/131] ( 93s left) perf (i915-ref-count)
[940.677871] Starting subtest: i915-ref-count
[940.735462] Subtest i915-ref-count: SUCCESS (0.057s)
[940.787851] [118/131] ( 92s left) gem_ctx_isolation (nonpriv)
[940.851927] Starting subtest: nonpriv
[940.852970] Starting dynamic subtest: rcs0
[941.132718] Dynamic subtest rcs0: SUCCESS (0.280s)
[941.143075] Starting dynamic subtest: bcs0
[941.387205] Dynamic subtest bcs0: SUCCESS (0.253s)
[941.398933] Starting dynamic subtest: vcs0
[941.669872] Dynamic subtest vcs0: SUCCESS (0.282s)
[941.681101] Starting dynamic subtest: vcs1
[942.928123] Dynamic subtest vcs1: SUCCESS (1.257s)
[942.937389] Starting dynamic subtest: vecs0
[944.978435] Dynamic subtest vecs0: SUCCESS (2.049s)
[944.990268] Starting dynamic subtest: ccs0
[946.988933] Dynamic subtest ccs0: SUCCESS (2.010s)
[946.989980] Subtest nonpriv: SUCCESS (6.138s)
[947.264817] [119/131] ( 86s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[947.331422] Starting subtest: y-tiled-to-vebox-x-tiled
[947.333422] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[947.538842] [120/131] ( 86s left) kms_atomic_transition (plane-use-after-nonblocking-unbind)
[947.637502] Starting subtest: plane-use-after-nonblocking-unbind
[947.638407] Starting dynamic subtest: pipe-A-eDP-1
[947.698098] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.061s)
[948.002259] Starting dynamic subtest: pipe-B-eDP-1
[948.782754] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.789s)
[949.090291] Subtest plane-use-after-nonblocking-unbind: SUCCESS (1.454s)
[949.960962] [121/131] ( 83s left) i915_hangman (engine-error-state-capture)
[950.029581] Starting subtest: engine-error-state-capture
[950.030587] Starting dynamic subtest: rcs0
[953.653815] Dynamic subtest rcs0: SUCCESS (3.622s)
[953.666023] Starting dynamic subtest: bcs0
[959.671207] Dynamic subtest bcs0: SUCCESS (6.015s)
[959.683939] Starting dynamic subtest: vcs0
[965.621671] Dynamic subtest vcs0: SUCCESS (5.947s)
[965.632021] Starting dynamic subtest: vcs1
[971.638451] Dynamic subtest vcs1: SUCCESS (6.013s)
[971.650746] Starting dynamic subtest: vecs0
[977.657113] Dynamic subtest vecs0: SUCCESS (6.015s)
[977.670018] Starting dynamic subtest: ccs0
[983.667878] Dynamic subtest ccs0: SUCCESS (6.009s)
[983.669467] Subtest engine-error-state-capture: SUCCESS (33.639s)
[983.768161] [122/131] ( 50s left) gem_exec_schedule (pi-shared-iova)
[983.950812] Starting subtest: pi-shared-iova
[983.951861] Starting dynamic subtest: rcs0
[984.076011] Dynamic subtest rcs0: SUCCESS (0.125s)
[984.088091] Starting dynamic subtest: vcs0
[984.158935] Dynamic subtest vcs0: SUCCESS (0.084s)
[984.173819] Starting dynamic subtest: vcs1
[984.245227] Dynamic subtest vcs1: SUCCESS (0.085s)
[984.257859] Starting dynamic subtest: vecs0
[984.331897] Dynamic subtest vecs0: SUCCESS (0.086s)
[984.343827] Starting dynamic subtest: ccs0
[984.445309] Dynamic subtest ccs0: SUCCESS (0.113s)
[984.446990] Subtest pi-shared-iova: SUCCESS (0.497s)
[985.823934] [123/131] ( 48s left) syncobj_wait (invalid-reset-illegal-handle)
[985.874225] Starting subtest: invalid-reset-illegal-handle
[985.875986] Subtest invalid-reset-illegal-handle: SUCCESS (0.000s)
[985.995673] [124/131] ( 47s left) syncobj_wait (single-wait-all-for-submit-signaled)
[986.050555] Starting subtest: single-wait-all-for-submit-signaled
[986.051413] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[986.173718] [125/131] ( 47s left) kms_cursor_crc (cursor-sliding-512x170)
[986.439890] Starting subtest: cursor-sliding-512x170
[986.441945] Subtest cursor-sliding-512x170: SKIP (0.001s)
[986.627999] [126/131] ( 47s left) kms_chamelium_hpd (hdmi-hpd-fast)
[986.995650] Subtest hdmi-hpd-fast: SKIP (0.000s)
[987.838464] [127/131] ( 46s left) gem_ctx_persistence (hostile)
[987.896661] Starting subtest: hostile
[997.404123] Subtest hostile: SUCCESS (9.502s)
[997.582264] [128/131] ( 36s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt)
[997.679376] Starting subtest: fbc-rgb565-draw-blt
[1001.870371] Subtest fbc-rgb565-draw-blt: SUCCESS (4.191s)
[1003.093482] [129/131] ( 31s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite)
[1003.183248] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite
[1012.479622] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (9.294s)
[1012.648570] [130/131] ( 21s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[1012.749337] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[1012.751190] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[1012.865256] [131/131] ( 21s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-move)
[1012.965907] Starting subtest: fbc-2p-scndscrn-spr-indfb-move
[1012.967757] Subtest fbc-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[1013.088111] 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.