[56.723286] Initializing watchdogs
[56.723430]   /dev/watchdog0
[56.739458] [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.744208] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.746415] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.749809] [001/131] (960s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[56.887742] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[56.891987] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.001s)
[57.023405] [002/131] (960s left) gem_exec_fence (submit3)
[57.196092] Starting subtest: submit3
[57.200665] Subtest submit3: SKIP (0.000s)
[59.016995] [FACT gem_exec_fence (submit3)] new: kernel.kmod_is_loaded.vgem: true
[59.022433] [003/131] (958s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[59.127459] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt
[59.131846] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[59.244644] [004/131] (958s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-wc)
[59.351808] Starting subtest: fbc-rgb101010-draw-mmap-wc
[63.919765] Subtest fbc-rgb101010-draw-mmap-wc: SUCCESS (4.573s)
[65.062056] [005/131] (952s left) gem_exec_balancer (noheartbeat)
[65.332889] Starting subtest: noheartbeat
[65.337465] Subtest noheartbeat: SKIP (0.000s)
[65.466149] [006/131] (952s left) kms_chamelium_color (ctm-blue-to-red)
[65.566521] Subtest ctm-blue-to-red: SKIP (0.000s)
[65.610788] [007/131] (951s left) gem_workarounds (suspend-resume)
[65.674103] Starting subtest: suspend-resume
[81.512042] Subtest suspend-resume: SUCCESS (5.539s)
[81.672508] [008/131] (935s left) kms_plane_scaling (planes-downscale-factor-0-75-unity-scaling)
[81.779530] Starting subtest: planes-downscale-factor-0-75-unity-scaling
[81.781824] Starting dynamic subtest: pipe-A
[81.844358] Dynamic subtest pipe-A: SUCCESS (0.065s)
[81.858508] Starting dynamic subtest: pipe-B
[82.995215] Dynamic subtest pipe-B: SUCCESS (1.149s)
[83.004293] Starting dynamic subtest: pipe-C
[84.107355] Dynamic subtest pipe-C: SUCCESS (1.111s)
[84.117465] Starting dynamic subtest: pipe-D
[85.232429] Dynamic subtest pipe-D: SUCCESS (1.124s)
[85.234685] Subtest planes-downscale-factor-0-75-unity-scaling: SUCCESS (3.451s)
[86.422767] [009/131] (931s left) gem_pxp (create-regular-buffer)
[86.555098] Starting subtest: create-regular-buffer
[86.557313] Subtest create-regular-buffer: SUCCESS (0.000s)
[86.698925] [010/131] (930s left) gem_exec_params (rel-constants-invalid-rel-gen5)
[86.756303] Starting subtest: rel-constants-invalid-rel-gen5
[86.758666] Subtest rel-constants-invalid-rel-gen5: SUCCESS (0.000s)
[86.880459] [011/131] (930s left) gem_exec_nop (basic-series)
[86.954920] Starting subtest: basic-series
[103.192264] Subtest basic-series: SUCCESS (16.242s)
[103.463559] [012/131] (914s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-wc)
[103.586718] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-wc
[103.591080] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[103.731113] [013/131] (914s left) kms_psr (fbc-psr-primary-page-flip)
[103.827115] Starting subtest: fbc-psr-primary-page-flip
[103.829437] Starting dynamic subtest: eDP-1
[103.833803] Dynamic subtest eDP-1: SKIP (0.000s)
[103.838133] Subtest fbc-psr-primary-page-flip: SKIP (0.000s)
[103.919970] [014/131] (913s left) i915_pm_rps (engine-order)
[103.979397] Starting subtest: engine-order
[109.066130] Subtest engine-order: SUCCESS (5.085s)
[109.229096] [015/131] (908s left) gem_softpin (evict-active)
[109.303418] Starting subtest: evict-active
[109.313834] Subtest evict-active: SUCCESS (0.011s)
[109.438456] [016/131] (908s left) syncobj_timeline (wait-delayed-signal)
[109.503208] Starting subtest: wait-delayed-signal
[109.608393] Subtest wait-delayed-signal: SUCCESS (0.103s)
[109.721154] [017/131] (908s left) kms_panel_fitting (atomic-fastset)
[109.830296] Starting subtest: atomic-fastset
[109.832521] Starting dynamic subtest: pipe-A-eDP-1
[109.971177] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.142s)
[109.983505] Starting dynamic subtest: pipe-B-eDP-1
[111.154291] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.182s)
[111.166244] Starting dynamic subtest: pipe-C-eDP-1
[112.329423] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.174s)
[112.341599] Starting dynamic subtest: pipe-D-eDP-1
[113.474164] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.145s)
[113.476532] Subtest atomic-fastset: SUCCESS (3.646s)
[114.735174] [018/131] (903s left) kms_big_fb (x-tiled-16bpp-rotate-270)
[114.841132] Starting subtest: x-tiled-16bpp-rotate-270
[114.861999] Subtest x-tiled-16bpp-rotate-270: SKIP (0.020s)
[115.034832] [019/131] (903s left) api_intel_bb (render-ccs)
[115.106140] Starting subtest: render-ccs
[115.482085] Subtest render-ccs: SUCCESS (0.375s)
[115.657321] [020/131] (902s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc)
[115.759247] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc
[115.761757] Starting dynamic subtest: pipe-A-eDP-1
[115.784601] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[115.796275] Starting dynamic subtest: pipe-B-eDP-1
[115.817923] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[115.826412] Starting dynamic subtest: pipe-C-eDP-1
[115.847684] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[115.855874] Starting dynamic subtest: pipe-D-eDP-1
[115.878626] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[115.883030] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[117.993929] [021/131] (900s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted-signaled)
[118.052239] Starting subtest: multi-wait-all-for-submit-unsubmitted-signaled
[118.160123] Subtest multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.107s)
[118.261239] [022/131] (899s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-move)
[118.355814] Starting subtest: psr-2p-primscrn-cur-indfb-move
[118.360283] Subtest psr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[118.480916] [023/131] (899s left) gem_lmem_swapping (heavy-multi)
[118.524984] Subtest heavy-multi: SKIP (0.000s)
[118.564960] [024/131] (899s left) kms_async_flips (test-cursor)
[118.663704] Starting subtest: test-cursor
[118.668164] Subtest test-cursor: SKIP (0.000s)
[119.919267] [025/131] (898s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-sf)
[124.977268] Starting subtest: fbc-psr2-overlay-plane-move-continuous-exceed-sf
[124.979816] Starting dynamic subtest: pipe-A-eDP-1
[125.776130] Dynamic subtest pipe-A-eDP-1: SKIP (0.797s)
[125.786969] Starting dynamic subtest: pipe-B-eDP-1
[126.906952] Dynamic subtest pipe-B-eDP-1: SKIP (1.130s)
[126.911318] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-sf: SKIP (1.931s)
[128.118567] [026/131] (890s left) kms_plane_multiple (2x-tiling-4)
[128.224836] Starting subtest: 2x-tiling-4
[128.229417] Subtest 2x-tiling-4: SKIP (0.000s)
[128.322549] [027/131] (890s left) gem_ctx_param (invalid-param-set)
[128.391801] Starting subtest: invalid-param-set
[128.394095] Subtest invalid-param-set: SUCCESS (0.000s)
[128.494488] [028/131] (889s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[128.586424] Starting subtest: bad-pixel-format-y-tiled-ccs
[128.588773] Starting dynamic subtest: pipe-A-eDP-1
[128.612616] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[128.631107] Starting dynamic subtest: pipe-B-eDP-1
[128.653606] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[128.671047] Starting dynamic subtest: pipe-C-eDP-1
[128.694330] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[128.712288] Starting dynamic subtest: pipe-D-eDP-1
[128.735778] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[128.739995] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.004s)
[130.876545] [029/131] (887s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[130.974496] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[130.976834] Starting dynamic subtest: pipe-A
[130.994972] Dynamic subtest pipe-A: SKIP (0.017s)
[131.008459] Starting dynamic subtest: pipe-B
[131.027518] Dynamic subtest pipe-B: SKIP (0.031s)
[131.036946] Starting dynamic subtest: pipe-C
[131.055903] Dynamic subtest pipe-C: SKIP (0.031s)
[131.064654] Starting dynamic subtest: pipe-D
[131.087562] Dynamic subtest pipe-D: SKIP (0.031s)
[131.091257] Subtest plane-downscale-factor-0-5-with-modifiers: SKIP (0.111s)
[131.235956] [030/131] (887s left) syncobj_timeline (multi-wait-all-available-submitted-signaled)
[131.300962] Starting subtest: multi-wait-all-available-submitted-signaled
[131.303378] Subtest multi-wait-all-available-submitted-signaled: SUCCESS (0.000s)
[131.442639] [031/131] (887s left) perf (missing-sample-flags)
[131.571650] Starting subtest: missing-sample-flags
[131.573828] Subtest missing-sample-flags: SUCCESS (0.000s)
[131.614937] [032/131] (886s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-pwrite)
[131.716360] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-pwrite
[137.900166] Subtest fbc-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (6.188s)
[139.082703] [033/131] (879s left) kms_pm_rpm (system-suspend-idle)
[141.337642] Starting subtest: system-suspend-idle
[161.049086] Subtest system-suspend-idle: SUCCESS (4.629s)
[161.460181] [034/131] (857s left) kms_vblank (wait-forked)
[161.576690] Starting subtest: wait-forked
[161.578712] Starting dynamic subtest: pipe-A-eDP-1
[163.387248] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.810s)
[163.401936] Starting dynamic subtest: pipe-D-eDP-1
[165.925641] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.534s)
[165.927632] Subtest wait-forked: SUCCESS (4.349s)
[166.812926] [035/131] (851s left) kms_big_fb (y-tiled-32bpp-rotate-0)
[166.923647] Starting subtest: y-tiled-32bpp-rotate-0
[166.928005] Subtest y-tiled-32bpp-rotate-0: SKIP (0.000s)
[167.052584] [036/131] (851s left) kms_pipe_crc_basic (read-crc)
[167.151066] Starting subtest: read-crc
[167.153017] Starting dynamic subtest: pipe-A-eDP-1
[167.870518] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.718s)
[167.881425] Starting dynamic subtest: pipe-B-eDP-1
[169.251180] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.379s)
[169.261777] Starting dynamic subtest: pipe-C-eDP-1
[170.544498] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.292s)
[170.554956] Starting dynamic subtest: pipe-D-eDP-1
[171.880259] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.333s)
[171.882553] Subtest read-crc: SUCCESS (4.727s)
[172.722221] [037/131] (846s left) kms_cursor_legacy (single-move)
[172.852272] Starting subtest: single-move
[172.854494] Starting dynamic subtest: pipe-A
[178.234113] Dynamic subtest pipe-A: SUCCESS (5.379s)
[178.246059] Starting dynamic subtest: pipe-B
[183.616423] Dynamic subtest pipe-B: SUCCESS (5.380s)
[183.627508] Starting dynamic subtest: pipe-C
[188.998802] Dynamic subtest pipe-C: SUCCESS (5.380s)
[189.010088] Starting dynamic subtest: pipe-D
[194.379921] Dynamic subtest pipe-D: SUCCESS (5.380s)
[194.391496] Starting dynamic subtest: all-pipes
[199.769253] Dynamic subtest all-pipes: SUCCESS (5.387s)
[199.771903] Subtest single-move: SUCCESS (26.915s)
[199.937817] [038/131] (818s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-sf)
[204.992336] Starting subtest: pr-overlay-plane-move-continuous-exceed-sf
[204.996946] Subtest pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.002s)
[205.852217] [039/131] (813s left) gem_unref_active_buffers
[206.085850] [040/131] (812s left) kms_psr (fbc-psr2-primary-mmap-gtt)
[206.174080] Starting subtest: fbc-psr2-primary-mmap-gtt
[206.176405] Starting dynamic subtest: eDP-1
[206.180623] Dynamic subtest eDP-1: SKIP (0.000s)
[206.185308] Subtest fbc-psr2-primary-mmap-gtt: SKIP (0.001s)
[206.266462] [041/131] (812s left) syncobj_wait (invalid-signal-zero-handles)
[206.323492] Starting subtest: invalid-signal-zero-handles
[206.325431] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[206.446436] [042/131] (812s left) gen9_exec_parse (bb-oversize)
[206.499467] Subtest bb-oversize: SKIP (0.000s)
[206.587926] [043/131] (812s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted)
[206.652543] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted
[206.756251] Subtest etime-multi-wait-all-for-submit-available-unsubmitted: SUCCESS (0.102s)
[206.857990] [044/131] (812s left) gem_exec_reloc (basic-write-cpu-noreloc)
[206.923851] Subtest basic-write-cpu-noreloc: SKIP (0.000s)
[206.990302] [045/131] (812s left) gem_mmap_offset (bad-flags)
[207.044192] Starting subtest: bad-flags
[207.046432] Subtest bad-flags: SUCCESS (0.000s)
[207.193133] [046/131] (811s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[207.311700] Starting subtest: cursor-vs-flip-legacy
[236.615314] Subtest cursor-vs-flip-legacy: SUCCESS (29.303s)
[237.471187] [047/131] (781s left) kms_rotation_crc (sprite-rotation-90-pos-100-0)
[237.571473] Starting subtest: sprite-rotation-90-pos-100-0
[237.575476] Subtest sprite-rotation-90-pos-100-0: SKIP (0.001s)
[237.668075] [048/131] (781s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[237.772563] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip
[238.774947] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip: SUCCESS (1.004s)
[239.003510] [049/131] (780s left) gem_create (busy-create)
[239.062958] Starting subtest: busy-create
[239.065147] Starting dynamic subtest: smem0
[272.325475] Dynamic subtest smem0: SUCCESS (33.259s)
[272.328087] Subtest busy-create: SUCCESS (33.260s)
[272.563178] [050/131] (746s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-render)
[272.677951] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-render
[276.828601] Subtest fbc-1p-offscreen-pri-indfb-draw-render: SUCCESS (4.155s)
[277.969164] [051/131] (741s left) perf (invalid-open-flags)
[278.079279] Starting subtest: invalid-open-flags
[278.081666] Subtest invalid-open-flags: SUCCESS (0.000s)
[278.130450] [052/131] (741s left) perf (oa-formats)
[278.239475] Starting subtest: oa-formats
[278.241825] Starting dynamic subtest: 0-rcs0
[278.254248] Dynamic subtest 0-rcs0: SUCCESS (0.015s)
[278.264242] Starting dynamic subtest: 1-vcs1
[278.274797] Dynamic subtest 1-vcs1: SUCCESS (0.020s)
[278.277152] Subtest oa-formats: SUCCESS (0.036s)
[278.317503] [053/131] (741s left) kms_big_fb (4-tiled-8bpp-rotate-270)
[278.442600] Starting subtest: 4-tiled-8bpp-rotate-270
[278.475715] Subtest 4-tiled-8bpp-rotate-270: SKIP (0.032s)
[278.618835] [054/131] (740s left) kms_cursor_crc (cursor-onscreen-64x21)
[278.789162] Starting subtest: cursor-onscreen-64x21
[278.834067] Subtest cursor-onscreen-64x21: SKIP (0.042s)
[279.090811] [055/131] (740s left) kms_rotation_crc (primary-4-tiled-reflect-x-180)
[279.205261] Starting subtest: primary-4-tiled-reflect-x-180
[286.220329] Subtest primary-4-tiled-reflect-x-180: SUCCESS (7.014s)
[287.417896] [056/131] (732s left) kms_cursor_crc (cursor-onscreen-256x256)
[287.661179] Starting subtest: cursor-onscreen-256x256
[287.663483] Starting dynamic subtest: pipe-A-eDP-1
[289.826692] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.163s)
[289.855443] Starting dynamic subtest: pipe-D-eDP-1
[292.556276] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.706s)
[292.558582] Subtest cursor-onscreen-256x256: SUCCESS (4.895s)
[293.388335] [057/131] (726s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-msflip-blt)
[293.492339] Starting subtest: fbcpsr-1p-primscrn-shrfb-msflip-blt
[300.909716] Subtest fbcpsr-1p-primscrn-shrfb-msflip-blt: SUCCESS (7.419s)
[301.072130] [058/131] (718s left) kms_async_flips (async-flip-with-page-flip-events-tiled)
[301.158850] Starting subtest: async-flip-with-page-flip-events-tiled
[301.530952] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs
[304.297303] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: SUCCESS (2.765s)
[304.308155] Starting dynamic subtest: pipe-A-eDP-1-4
[306.460153] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.163s)
[306.469762] Starting dynamic subtest: pipe-A-eDP-1-4
[308.470851] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.009s)
[308.483088] Starting dynamic subtest: pipe-A-eDP-1-x
[310.660414] Dynamic subtest pipe-A-eDP-1-x: SUCCESS (2.189s)
[311.038850] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs
[313.827759] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: SUCCESS (2.795s)
[313.837469] Starting dynamic subtest: pipe-B-eDP-1-4
[316.014295] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.186s)
[316.025289] Starting dynamic subtest: pipe-B-eDP-1-4
[318.024490] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.009s)
[318.034159] Starting dynamic subtest: pipe-B-eDP-1-x
[320.214123] Dynamic subtest pipe-B-eDP-1-x: SUCCESS (2.189s)
[320.590283] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs
[323.354194] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.770s)
[323.365733] Starting dynamic subtest: pipe-C-eDP-1-4
[325.517979] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.163s)
[325.527680] Starting dynamic subtest: pipe-C-eDP-1-4
[327.528994] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.009s)
[327.543186] Starting dynamic subtest: pipe-C-eDP-1-x
[329.707547] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.177s)
[330.084048] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs
[332.903304] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.813s)
[332.913031] Starting dynamic subtest: pipe-D-eDP-1-4
[335.062701] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.172s)
[335.072856] Starting dynamic subtest: pipe-D-eDP-1-4
[337.073263] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.009s)
[337.083400] Starting dynamic subtest: pipe-D-eDP-1-x
[339.252033] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.178s)
[339.254370] Subtest async-flip-with-page-flip-events-tiled: SUCCESS (38.094s)
[340.494197] [059/131] (679s left) perf (polling)
[340.602286] Starting subtest: polling
[340.604627] Starting dynamic subtest: 0-rcs0
[350.634100] Dynamic subtest 0-rcs0: SUCCESS (10.030s)
[350.643567] Starting dynamic subtest: 1-vcs1
[360.655892] Dynamic subtest 1-vcs1: SUCCESS (10.020s)
[360.658468] Subtest polling: SUCCESS (20.053s)
[360.705448] [060/131] (659s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[360.813309] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[360.817404] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[360.943115] [061/131] (658s left) syncobj_timeline (reset-signaled)
[361.004786] Starting subtest: reset-signaled
[361.007071] Subtest reset-signaled: SUCCESS (0.000s)
[361.122391] [062/131] (658s left) kms_rmfb (rmfb-ioctl)
[361.216388] Starting subtest: rmfb-ioctl
[361.218589] Starting dynamic subtest: pipe-A-eDP-1
[361.320347] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.106s)
[361.334194] Starting dynamic subtest: pipe-B-eDP-1
[362.538257] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.215s)
[362.552549] Starting dynamic subtest: pipe-C-eDP-1
[363.665573] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.124s)
[363.675887] Starting dynamic subtest: pipe-D-eDP-1
[364.793666] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.127s)
[364.796099] Subtest rmfb-ioctl: SUCCESS (3.581s)
[366.032463] [063/131] (653s left) gem_userptr_blits (create-destroy-sync)
[366.110938] Starting subtest: create-destroy-sync
[371.125937] Subtest create-destroy-sync: SUCCESS (5.016s)
[371.298897] [064/131] (648s left) gem_exec_reloc (basic-gtt-cpu-active)
[371.363932] Subtest basic-gtt-cpu-active: SKIP (0.000s)
[371.446525] [065/131] (648s left) api_intel_allocator (execbuf-with-allocator)
[371.500893] Starting subtest: execbuf-with-allocator
[371.507404] Subtest execbuf-with-allocator: SUCCESS (0.009s)
[371.677406] [066/131] (648s left) kms_lease (cursor-implicit-plane)
[371.777589] Starting subtest: cursor-implicit-plane
[371.779916] Starting dynamic subtest: pipe-A-eDP-1
[372.236276] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.457s)
[372.246474] Starting dynamic subtest: pipe-B-eDP-1
[373.327238] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.090s)
[373.344966] Starting dynamic subtest: pipe-C-eDP-1
[374.424847] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.098s)
[374.435154] Starting dynamic subtest: pipe-D-eDP-1
[375.564405] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.137s)
[375.566924] Subtest cursor-implicit-plane: SUCCESS (3.788s)
[376.454522] [067/131] (643s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions-varying-size)
[376.570739] Starting subtest: cursorA-vs-flipB-atomic-transitions-varying-size
[376.575394] Subtest cursorA-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[376.740564] [068/131] (643s left) syncobj_wait (invalid-signal-illegal-handle)
[376.797081] Starting subtest: invalid-signal-illegal-handle
[376.799090] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[376.923295] [069/131] (643s left) kms_psr (psr-primary-blt)
[377.012700] Starting subtest: psr-primary-blt
[377.015069] Starting dynamic subtest: eDP-1
[378.719999] Dynamic subtest eDP-1: SUCCESS (1.708s)
[378.722411] Subtest psr-primary-blt: SUCCESS (1.709s)
[379.620822] [070/131] (640s left) kms_ccs (crc-sprite-planes-basic-y-tiled-gen12-rc-ccs)
[379.720337] Starting subtest: crc-sprite-planes-basic-y-tiled-gen12-rc-ccs
[379.722559] Starting dynamic subtest: pipe-A-eDP-1
[379.781867] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[379.793032] Starting dynamic subtest: pipe-B-eDP-1
[379.851499] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[379.862442] Starting dynamic subtest: pipe-C-eDP-1
[379.918653] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[379.928450] Starting dynamic subtest: pipe-D-eDP-1
[379.985443] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[379.990070] Subtest crc-sprite-planes-basic-y-tiled-gen12-rc-ccs: SKIP (0.009s)
[382.023786] [071/131] (638s left) kms_psr (pr-primary-blt)
[382.118917] Starting subtest: pr-primary-blt
[382.123617] Subtest pr-primary-blt: SKIP (0.000s)
[382.218572] [072/131] (638s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-move)
[382.334470] Starting subtest: psr-2p-primscrn-spr-indfb-move
[382.338988] Subtest psr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[382.458205] [073/131] (637s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[382.565802] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[382.568267] Starting dynamic subtest: pipe-A-default-mode
[383.724609] Dynamic subtest pipe-A-default-mode: SKIP (1.156s)
[383.728750] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SKIP (1.157s)
[383.894217] [074/131] (636s left) gem_exec_schedule (u-semaphore-noskip)
[383.979346] Starting subtest: u-semaphore-noskip
[384.372560] Subtest u-semaphore-noskip: SUCCESS (0.399s)
[384.731849] [075/131] (635s left) i915_drm_fdinfo (memory-info-idle)
[384.775586] Starting subtest: memory-info-idle
[384.777930] Starting dynamic subtest: smem0
[384.827137] Dynamic subtest smem0: SUCCESS (0.054s)
[384.829565] Subtest memory-info-idle: SUCCESS (0.055s)
[384.926307] [076/131] (635s left) i915_selftest (live)
[388.549836] Starting subtest: live
[388.552735] Starting dynamic subtest: sanitycheck
[393.422622] Dynamic subtest sanitycheck: SUCCESS (4.872s)
[393.432146] Starting dynamic subtest: uncore
[397.880703] Dynamic subtest uncore: SUCCESS (4.456s)
[397.890820] Starting dynamic subtest: workarounds
[415.502571] Dynamic subtest workarounds: FAIL (17.027s)
[415.514504] Starting dynamic subtest: gt_engines
[419.683325] Dynamic subtest gt_engines: SUCCESS (4.771s)
[419.692342] Starting dynamic subtest: gt_timelines
[437.486737] Dynamic subtest gt_timelines: SUCCESS (17.800s)
[437.496837] Starting dynamic subtest: gt_contexts
[440.443409] Dynamic subtest gt_contexts: SUCCESS (2.955s)
[440.454416] Starting dynamic subtest: gt_lrc
[446.800000] Dynamic subtest gt_lrc: SUCCESS (6.354s)
[446.809902] Starting dynamic subtest: gt_mocs
[449.864847] Dynamic subtest gt_mocs: SUCCESS (3.063s)
[449.874819] Starting dynamic subtest: gt_pm
[456.786444] Dynamic subtest gt_pm: SUCCESS (6.920s)
[456.801628] Starting dynamic subtest: gt_heartbeat
[461.277598] Dynamic subtest gt_heartbeat: SUCCESS (4.489s)
[461.287807] Starting dynamic subtest: gt_tlb
[470.749644] Dynamic subtest gt_tlb: SUCCESS (9.470s)
[470.759144] Starting dynamic subtest: requests
[484.239504] Dynamic subtest requests: SUCCESS (13.487s)
[484.249433] Starting dynamic subtest: migrate
[492.326394] Dynamic subtest migrate: SUCCESS (8.085s)
[492.336090] Starting dynamic subtest: active
[496.830562] Dynamic subtest active: SUCCESS (4.501s)
[496.840899] Starting dynamic subtest: objects
[501.675772] Dynamic subtest objects: SUCCESS (4.843s)
[501.685771] Starting dynamic subtest: mman
[506.707115] Dynamic subtest mman: SUCCESS (5.029s)
[506.717159] Starting dynamic subtest: dmabuf
[511.197267] Dynamic subtest dmabuf: SUCCESS (4.488s)
[511.207310] Starting dynamic subtest: vma
[515.636278] Dynamic subtest vma: SUCCESS (4.437s)
[515.646246] Starting dynamic subtest: coherency
[521.179115] Dynamic subtest coherency: SUCCESS (5.541s)
[521.189184] Starting dynamic subtest: gtt
[531.177860] Dynamic subtest gtt: SUCCESS (9.996s)
[531.188050] Starting dynamic subtest: gem
[539.176888] Dynamic subtest gem: SUCCESS (7.998s)
[539.187286] Starting dynamic subtest: evict
[544.818677] Dynamic subtest evict: SUCCESS (5.638s)
[544.828973] Starting dynamic subtest: hugepages
[555.937861] Dynamic subtest hugepages: SUCCESS (11.117s)
[555.948024] Starting dynamic subtest: gem_contexts
[583.262505] Dynamic subtest gem_contexts: SUCCESS (27.323s)
[583.271904] Starting dynamic subtest: client
[586.188057] Dynamic subtest client: SUCCESS (2.925s)
[586.203634] Starting dynamic subtest: gem_migrate
[590.645639] Dynamic subtest gem_migrate: SUCCESS (4.455s)
[590.655516] Starting dynamic subtest: reset
[595.164274] Dynamic subtest reset: SUCCESS (4.517s)
[595.174599] Starting dynamic subtest: memory_region
[599.633761] Dynamic subtest memory_region: SUCCESS (4.467s)
[599.643534] Starting dynamic subtest: hangcheck
[612.843448] Dynamic subtest hangcheck: SUCCESS (13.208s)
[612.853396] Starting dynamic subtest: execlists
[617.341295] Dynamic subtest execlists: SUCCESS (4.495s)
[617.351483] Starting dynamic subtest: ring_submission
[621.821274] Dynamic subtest ring_submission: SUCCESS (4.478s)
[621.831108] Starting dynamic subtest: perf
[626.308568] Dynamic subtest perf: SUCCESS (4.485s)
[626.318873] Starting dynamic subtest: slpc
[640.494111] Dynamic subtest slpc: SUCCESS (14.183s)
[640.504071] Starting dynamic subtest: guc
[644.547542] Dynamic subtest guc: SUCCESS (4.051s)
[644.557268] Starting dynamic subtest: guc_multi_lrc
[649.066729] Dynamic subtest guc_multi_lrc: SUCCESS (4.516s)
[649.075266] Starting dynamic subtest: guc_hang
[654.120262] Dynamic subtest guc_hang: SUCCESS (5.047s)
[654.130068] Starting dynamic subtest: late_gt_pm
[657.007440] Dynamic subtest late_gt_pm: SUCCESS (2.889s)
[657.010105] Subtest live: FAIL (268.450s)
[657.038074] [FACT i915_selftest (live)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[657.038774] [FACT i915_selftest (live)] deleted: kernel.kmod_is_loaded.i915: true
[657.042031] [077/131] (363s left) gem_userptr_blits (nohangcheck)
[658.617958] Starting subtest: nohangcheck
[658.902062] Subtest nohangcheck: SUCCESS (0.280s)
[659.019504] [FACT gem_userptr_blits (nohangcheck)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[659.020769] [FACT gem_userptr_blits (nohangcheck)] new: kernel.kmod_is_loaded.i915: true
[659.024919] [078/131] (361s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs)
[659.134309] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs
[659.136626] Starting dynamic subtest: pipe-A-eDP-1
[659.994021] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.854s)
[660.002674] Starting dynamic subtest: pipe-B-eDP-1
[661.603419] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.605s)
[661.615526] Starting dynamic subtest: pipe-C-eDP-1
[663.194247] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.591s)
[663.204347] Starting dynamic subtest: pipe-D-eDP-1
[664.753298] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.556s)
[664.755474] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs: SUCCESS (5.611s)
[665.977345] [079/131] (354s left) gem_softpin (allocator-nopin)
[666.045936] Starting subtest: allocator-nopin
[666.150474] Subtest allocator-nopin: SUCCESS (0.103s)
[666.321970] [080/131] (354s left) kms_draw_crc (draw-method-pwrite)
[666.414602] Starting subtest: draw-method-pwrite
[666.416823] Starting dynamic subtest: xrgb8888-untiled
[666.979588] Dynamic subtest xrgb8888-untiled: SUCCESS (0.566s)
[666.989046] Starting dynamic subtest: rgb565-untiled
[667.424645] Dynamic subtest rgb565-untiled: SUCCESS (0.444s)
[667.434041] Starting dynamic subtest: xrgb2101010-untiled
[667.969059] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.544s)
[667.978297] Starting dynamic subtest: xbgr16161616f-untiled
[668.724825] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.754s)
[668.738502] Starting dynamic subtest: xrgb8888-xtiled
[670.557957] Dynamic subtest xrgb8888-xtiled: SUCCESS (1.830s)
[670.567834] Starting dynamic subtest: rgb565-xtiled
[671.646673] Dynamic subtest rgb565-xtiled: SUCCESS (1.088s)
[671.658980] Starting dynamic subtest: xrgb2101010-xtiled
[673.413556] Dynamic subtest xrgb2101010-xtiled: SUCCESS (1.766s)
[673.423070] Starting dynamic subtest: xbgr16161616f-xtiled
[676.702758] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (3.288s)
[676.712575] Starting dynamic subtest: xrgb8888-4tiled
[677.813891] Dynamic subtest xrgb8888-4tiled: SUCCESS (1.110s)
[677.823761] Starting dynamic subtest: rgb565-4tiled
[678.391791] Dynamic subtest rgb565-4tiled: SUCCESS (0.577s)
[678.402270] Starting dynamic subtest: xrgb2101010-4tiled
[679.481113] Dynamic subtest xrgb2101010-4tiled: SUCCESS (1.088s)
[679.496397] Starting dynamic subtest: xbgr16161616f-4tiled
[682.470399] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (2.986s)
[682.472570] Subtest draw-method-pwrite: SUCCESS (16.057s)
[682.619279] [081/131] (338s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[682.753506] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip
[682.757837] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[682.889244] [082/131] (337s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-plflip-blt)
[682.991361] Starting subtest: fbc-2p-primscrn-indfb-plflip-blt
[682.995294] Subtest fbc-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[683.101997] [083/131] (337s left) syncobj_timeline (host-signal-points)
[683.165201] Starting subtest: host-signal-points
[683.171201] Subtest host-signal-points: SUCCESS (0.004s)
[683.284896] [084/131] (337s left) gem_mmap_gtt (basic-small-bo)
[683.351277] Subtest basic-small-bo: SKIP (0.000s)
[683.414795] [085/131] (337s left) perf_pmu (semaphore-wait-idle)
[683.455824] Starting subtest: semaphore-wait-idle
[683.457662] Starting dynamic subtest: rcs0
[683.971002] Dynamic subtest rcs0: SUCCESS (0.514s)
[683.981502] Starting dynamic subtest: bcs0
[684.484496] Dynamic subtest bcs0: SUCCESS (0.510s)
[684.494989] Starting dynamic subtest: vcs0
[685.000894] Dynamic subtest vcs0: SUCCESS (0.512s)
[685.013427] Starting dynamic subtest: vcs1
[685.514447] Dynamic subtest vcs1: SUCCESS (0.511s)
[685.527097] Starting dynamic subtest: vecs0
[686.027326] Dynamic subtest vecs0: SUCCESS (0.510s)
[686.039462] Starting dynamic subtest: ccs0
[686.542728] Dynamic subtest ccs0: SUCCESS (0.512s)
[686.545782] Subtest semaphore-wait-idle: SUCCESS (3.087s)
[686.703237] [086/131] (334s left) kms_addfb_basic (no-handle)
[686.765170] Starting subtest: no-handle
[686.767340] Subtest no-handle: SUCCESS (0.000s)
[686.937584] [087/131] (334s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-pwrite)
[687.028774] Starting subtest: fbcpsr-rgb101010-draw-pwrite
[694.049426] Subtest fbcpsr-rgb101010-draw-pwrite: SUCCESS (7.022s)
[694.196996] [088/131] (326s left) gem_render_copy (yf-tiled-ccs-to-linear)
[694.260083] Starting subtest: yf-tiled-ccs-to-linear
[694.264610] Subtest yf-tiled-ccs-to-linear: SKIP (0.000s)
[694.469780] [089/131] (326s left) kms_flip (wf_vblank-ts-check)
[694.563491] Starting subtest: wf_vblank-ts-check
[694.565733] Starting dynamic subtest: A-eDP1
[703.500635] Dynamic subtest A-eDP1: SUCCESS (8.938s)
[703.509638] Starting dynamic subtest: B-eDP1
[712.423918] Dynamic subtest B-eDP1: SUCCESS (8.921s)
[712.434705] Starting dynamic subtest: C-eDP1
[721.344663] Dynamic subtest C-eDP1: SUCCESS (8.918s)
[721.355155] Starting dynamic subtest: D-eDP1
[730.275223] Dynamic subtest D-eDP1: SUCCESS (8.930s)
[730.277578] Subtest wf_vblank-ts-check: SUCCESS (35.716s)
[731.528723] [090/131] (289s left) gem_exec_schedule (deep)
[731.704107] Starting subtest: deep
[731.706752] Starting dynamic subtest: rcs0
[736.409650] Dynamic subtest rcs0: SKIP (4.701s)
[736.421994] Starting dynamic subtest: vcs0
[746.685888] Dynamic subtest vcs0: SUCCESS (10.278s)
[746.699054] Starting dynamic subtest: vcs1
[757.562689] Dynamic subtest vcs1: SUCCESS (10.875s)
[757.577389] Starting dynamic subtest: vecs0
[768.729299] Dynamic subtest vecs0: SUCCESS (11.165s)
[768.741041] Starting dynamic subtest: ccs0
[781.023558] Dynamic subtest ccs0: SUCCESS (12.293s)
[781.025954] Subtest deep: SUCCESS (49.316s)
[782.926735] [091/131] (238s left) kms_sequence (queue-idle)
[783.024362] Starting subtest: queue-idle
[783.026613] Starting dynamic subtest: pipe-A-eDP-1
[784.859295] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.834s)
[784.869289] Starting dynamic subtest: pipe-B-eDP-1
[787.381455] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.519s)
[787.391217] Starting dynamic subtest: pipe-C-eDP-1
[789.807264] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.424s)
[789.818647] Starting dynamic subtest: pipe-D-eDP-1
[792.237983] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.431s)
[792.240480] Subtest queue-idle: SUCCESS (9.215s)
[793.055463] [092/131] (228s left) gem_gtt_cpu_tlb
[793.179494] [093/131] (228s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[793.276828] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc
[799.791395] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.517s)
[799.927233] [094/131] (221s left) gem_eio (create-ext)
[800.155948] Starting subtest: create-ext
[800.480666] Subtest create-ext: SUCCESS (0.325s)
[800.662929] [095/131] (220s left) gem_partial_pwrite_pread (writes-after-reads-uncached)
[800.727380] Subtest writes-after-reads-uncached: SKIP (0.000s)
[800.818267] [096/131] (220s left) sysfs_preempt_timeout (idempotent)
[800.879400] Starting subtest: idempotent
[800.881451] Starting dynamic subtest: vecs0
[800.883365] Dynamic subtest vecs0: SUCCESS (0.000s)
[800.894598] Starting dynamic subtest: ccs0
[800.896417] Dynamic subtest ccs0: SUCCESS (0.000s)
[800.905658] Starting dynamic subtest: vcs0
[800.907487] Dynamic subtest vcs0: SUCCESS (0.000s)
[800.916645] Starting dynamic subtest: bcs0
[800.918472] Dynamic subtest bcs0: SUCCESS (0.000s)
[800.928229] Starting dynamic subtest: rcs0
[800.930438] Dynamic subtest rcs0: SUCCESS (0.000s)
[800.950080] Starting dynamic subtest: vcs1
[800.952383] Dynamic subtest vcs1: SUCCESS (0.000s)
[800.954307] Subtest idempotent: SUCCESS (0.007s)
[801.045016] [097/131] (220s left) gem_busy (semaphore)
[801.243636] Subtest semaphore: SKIP (0.000s)
[801.503476] [098/131] (220s left) sriov_basic (enable-vfs-bind-unbind-each)
[801.579090] Starting subtest: enable-vfs-bind-unbind-each
[801.581590] Starting dynamic subtest: numvfs-1
[801.655979] Dynamic subtest numvfs-1: FAIL (0.006s)
[801.667626] Starting dynamic subtest: numvfs-2
[801.740494] Dynamic subtest numvfs-2: FAIL (0.004s)
[801.752140] Starting dynamic subtest: numvfs-3
[801.827240] Dynamic subtest numvfs-3: FAIL (0.004s)
[801.838106] Starting dynamic subtest: numvfs-4
[801.910918] Dynamic subtest numvfs-4: FAIL (0.004s)
[801.922311] Starting dynamic subtest: numvfs-5
[801.996022] Dynamic subtest numvfs-5: FAIL (0.004s)
[802.006728] Starting dynamic subtest: numvfs-6
[802.079977] Dynamic subtest numvfs-6: FAIL (0.003s)
[802.091600] Starting dynamic subtest: numvfs-7
[802.164831] Dynamic subtest numvfs-7: FAIL (0.004s)
[802.175390] Starting dynamic subtest: numvfs-random
[802.248309] Dynamic subtest numvfs-random: FAIL (0.004s)
[802.250331] Subtest enable-vfs-bind-unbind-each: FAIL (0.038s)
[802.315118] [099/131] (219s left) kms_pm_lpsp (screens-disabled)
[803.430037] Starting subtest: screens-disabled
[803.434377] Subtest screens-disabled: SKIP (0.000s)
[803.525674] [100/131] (218s left) gem_exec_capture (capture-invisible)
[803.597219] Starting subtest: capture-invisible
[803.599601] Starting dynamic subtest: smem0
[803.603816] Dynamic subtest smem0: SKIP (0.000s)
[803.608060] Subtest capture-invisible: SKIP (0.001s)
[803.741485] [101/131] (217s left) gem_lmem_swapping (verify-ccs)
[803.784740] Subtest verify-ccs: SKIP (0.000s)
[803.834694] [102/131] (217s left) kms_joiner (basic-force-ultra-joiner)
[803.926708] Starting subtest: basic-force-ultra-joiner
[803.931064] Subtest basic-force-ultra-joiner: SKIP (0.000s)
[804.017174] [103/131] (217s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-wc)
[804.119468] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-wc
[810.700366] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.583s)
[810.851629] [104/131] (210s left) kms_color (legacy-gamma)
[810.945298] Starting subtest: legacy-gamma
[810.947162] Starting dynamic subtest: pipe-A-eDP-1
[811.652289] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.709s)
[811.663178] Starting dynamic subtest: pipe-B-eDP-1
[813.059063] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.404s)
[813.069292] Starting dynamic subtest: pipe-C-eDP-1
[814.426190] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.366s)
[814.438250] Starting dynamic subtest: pipe-D-eDP-1
[815.757616] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.332s)
[815.759930] Subtest legacy-gamma: SUCCESS (4.814s)
[816.593617] [105/131] (205s left) gem_exec_schedule (wide)
[816.783951] Starting subtest: wide
[816.786225] Starting dynamic subtest: rcs0
[826.982635] Dynamic subtest rcs0: SUCCESS (10.200s)
[826.992712] Starting dynamic subtest: vcs0
[839.240389] Dynamic subtest vcs0: SUCCESS (12.256s)
[839.252906] Starting dynamic subtest: vcs1
[851.055383] Dynamic subtest vcs1: SUCCESS (11.813s)
[851.071018] Starting dynamic subtest: vecs0
[863.504558] Dynamic subtest vecs0: SUCCESS (12.447s)
[863.517486] Starting dynamic subtest: ccs0
[878.356277] Dynamic subtest ccs0: SUCCESS (14.850s)
[878.358938] Subtest wide: SUCCESS (61.573s)
[881.386302] [106/131] (140s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-upscaling)
[881.500274] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-upscaling
[881.502598] Starting dynamic subtest: pipe-A-default-mode
[881.794023] Dynamic subtest pipe-A-default-mode: SKIP (0.290s)
[881.798825] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-upscaling: SKIP (0.292s)
[882.618762] [107/131] (139s left) kms_big_fb (linear-64bpp-rotate-180)
[882.718412] Starting subtest: linear-64bpp-rotate-180
[884.444324] Subtest linear-64bpp-rotate-180: SUCCESS (1.728s)
[884.641385] [108/131] (137s left) gem_ppgtt (blt-vs-render-ctxn)
[884.705744] Starting subtest: blt-vs-render-ctxN
[917.950671] Subtest blt-vs-render-ctxN: SUCCESS (33.237s)
[918.134264] [109/131] (103s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-plflip-blt)
[918.296659] Starting subtest: psr-2p-scndscrn-shrfb-plflip-blt
[918.300704] Subtest psr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[918.419110] [110/131] (103s left) kms_chamelium_frames (dp-frame-dump)
[918.818767] Subtest dp-frame-dump: SKIP (0.000s)
[919.610936] [111/131] (102s left) kms_psr2_su (page_flip-nv12)
[919.673324] Subtest page_flip-NV12: SKIP (0.000s)
[919.740665] [112/131] (102s left) gem_tiling_max_stride
[919.872188] [113/131] (102s left) perf_pmu (busy-idle)
[919.922765] Starting subtest: busy-idle
[919.925204] Starting dynamic subtest: rcs0
[920.635949] Dynamic subtest rcs0: SUCCESS (0.714s)
[920.649037] Starting dynamic subtest: bcs0
[921.303916] Dynamic subtest bcs0: SUCCESS (0.666s)
[921.316936] Starting dynamic subtest: vcs0
[923.327630] Dynamic subtest vcs0: SUCCESS (2.020s)
[923.341046] Starting dynamic subtest: vcs1
[925.311691] Dynamic subtest vcs1: SUCCESS (1.980s)
[925.324435] Starting dynamic subtest: vecs0
[927.296292] Dynamic subtest vecs0: SUCCESS (1.982s)
[927.309852] Starting dynamic subtest: ccs0
[927.962388] Dynamic subtest ccs0: SUCCESS (0.665s)
[927.964978] Subtest busy-idle: SUCCESS (8.042s)
[928.115621] [114/131] ( 94s left) dmabuf_sync_file (export-before-signal)
[928.159868] Starting subtest: export-before-signal
[928.162068] Subtest export-before-signal: SUCCESS (0.001s)
[928.243271] [115/131] ( 94s left) gem_exec_reloc (basic-cpu-read)
[928.301733] Subtest basic-cpu-read: SKIP (0.000s)
[928.390734] [116/131] ( 93s left) kms_hdr (bpc-switch)
[928.495166] Starting subtest: bpc-switch
[929.592236] Starting dynamic subtest: pipe-A-eDP-1
[933.083353] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.493s)
[933.085755] Subtest bpc-switch: SUCCESS (4.590s)
[933.244609] [117/131] ( 89s left) perf (i915-ref-count)
[933.351606] Starting subtest: i915-ref-count
[933.421972] Subtest i915-ref-count: SUCCESS (0.068s)
[933.480396] [118/131] ( 88s left) gem_ctx_isolation (nonpriv)
[933.538667] Starting subtest: nonpriv
[933.540898] Starting dynamic subtest: rcs0
[933.794280] Dynamic subtest rcs0: SUCCESS (0.255s)
[933.807282] Starting dynamic subtest: bcs0
[934.071827] Dynamic subtest bcs0: SUCCESS (0.277s)
[934.083716] Starting dynamic subtest: vcs0
[934.349241] Dynamic subtest vcs0: SUCCESS (0.277s)
[934.359403] Starting dynamic subtest: vcs1
[935.514516] Dynamic subtest vcs1: SUCCESS (1.164s)
[935.527540] Starting dynamic subtest: vecs0
[937.556261] Dynamic subtest vecs0: SUCCESS (2.040s)
[937.569823] Starting dynamic subtest: ccs0
[939.611572] Dynamic subtest ccs0: SUCCESS (2.054s)
[939.614248] Subtest nonpriv: SUCCESS (6.072s)
[939.884598] [119/131] ( 82s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[939.960407] Starting subtest: y-tiled-to-vebox-x-tiled
[939.964444] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[940.174607] [120/131] ( 82s left) kms_atomic_transition (plane-use-after-nonblocking-unbind)
[940.287199] Starting subtest: plane-use-after-nonblocking-unbind
[940.289534] Starting dynamic subtest: pipe-A-eDP-1
[940.353991] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.068s)
[940.656712] Starting dynamic subtest: pipe-B-eDP-1
[941.443044] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.799s)
[941.740820] Subtest plane-use-after-nonblocking-unbind: SUCCESS (1.454s)
[942.627632] [121/131] ( 79s left) i915_hangman (engine-error-state-capture)
[942.703937] Starting subtest: engine-error-state-capture
[942.706377] Starting dynamic subtest: rcs0
[946.206426] Dynamic subtest rcs0: SUCCESS (3.500s)
[946.219979] Starting dynamic subtest: bcs0
[952.223489] Dynamic subtest bcs0: SUCCESS (6.014s)
[952.237864] Starting dynamic subtest: vcs0
[958.238721] Dynamic subtest vcs0: SUCCESS (6.012s)
[958.251701] Starting dynamic subtest: vcs1
[964.190998] Dynamic subtest vcs1: SUCCESS (5.949s)
[964.203721] Starting dynamic subtest: vecs0
[969.181297] Dynamic subtest vecs0: SUCCESS (4.987s)
[969.195400] Starting dynamic subtest: ccs0
[974.238201] Dynamic subtest ccs0: SUCCESS (5.054s)
[974.241125] Subtest engine-error-state-capture: SUCCESS (31.533s)
[974.359202] [122/131] ( 48s left) gem_exec_schedule (pi-shared-iova)
[974.552945] Starting subtest: pi-shared-iova
[974.555498] Starting dynamic subtest: rcs0
[974.685929] Dynamic subtest rcs0: SUCCESS (0.133s)
[974.700194] Starting dynamic subtest: vcs0
[974.774235] Dynamic subtest vcs0: SUCCESS (0.088s)
[974.789667] Starting dynamic subtest: vcs1
[974.865050] Dynamic subtest vcs1: SUCCESS (0.091s)
[974.878326] Starting dynamic subtest: vecs0
[974.946654] Dynamic subtest vecs0: SUCCESS (0.079s)
[974.958851] Starting dynamic subtest: ccs0
[975.053951] Dynamic subtest ccs0: SUCCESS (0.107s)
[975.057018] Subtest pi-shared-iova: SUCCESS (0.503s)
[976.447621] [123/131] ( 46s left) syncobj_wait (invalid-reset-illegal-handle)
[976.498389] Starting subtest: invalid-reset-illegal-handle
[976.500704] Subtest invalid-reset-illegal-handle: SUCCESS (0.000s)
[976.637062] [124/131] ( 46s left) syncobj_wait (single-wait-all-for-submit-signaled)
[976.695776] Starting subtest: single-wait-all-for-submit-signaled
[976.698182] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[976.833157] [125/131] ( 45s left) kms_cursor_crc (cursor-sliding-512x170)
[977.125363] Starting subtest: cursor-sliding-512x170
[977.129281] Subtest cursor-sliding-512x170: SKIP (0.001s)
[977.297927] [126/131] ( 45s left) kms_chamelium_hpd (hdmi-hpd-fast)
[977.677643] Subtest hdmi-hpd-fast: SKIP (0.000s)
[978.499508] [127/131] ( 44s left) gem_ctx_persistence (hostile)
[978.572916] Starting subtest: hostile
[988.732103] Subtest hostile: SUCCESS (10.154s)
[988.890622] [128/131] ( 33s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt)
[989.008532] Starting subtest: fbc-rgb565-draw-blt
[993.219821] Subtest fbc-rgb565-draw-blt: SUCCESS (4.214s)
[994.445467] [129/131] ( 28s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite)
[994.557569] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite
[1003.840872] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (9.286s)
[1004.013370] [130/131] ( 18s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[1004.122478] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[1004.126399] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[1004.270568] [131/131] ( 18s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-move)
[1004.363921] Starting subtest: fbc-2p-scndscrn-spr-indfb-move
[1004.368282] Subtest fbc-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[1004.486540] 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.