[57.456553] Initializing watchdogs
[57.456630]   /dev/watchdog0
[57.470777] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[57.474953] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.477427] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.480869] [001/131] (960s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[57.611701] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[57.616036] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s)
[57.747720] [002/131] (960s left) gem_exec_fence (submit3)
[57.931897] Starting subtest: submit3
[57.936380] Subtest submit3: SKIP (0.000s)
[60.053427] [FACT gem_exec_fence (submit3)] new: kernel.kmod_is_loaded.vgem: true
[60.056214] [003/131] (957s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[60.171398] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt
[60.175594] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[60.291232] [004/131] (957s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-wc)
[60.403192] Starting subtest: fbc-rgb101010-draw-mmap-wc
[65.003691] Subtest fbc-rgb101010-draw-mmap-wc: SUCCESS (4.598s)
[66.132498] [005/131] (951s left) gem_exec_balancer (noheartbeat)
[66.392769] Starting subtest: noheartbeat
[66.397266] Subtest noheartbeat: SKIP (0.000s)
[66.515361] [006/131] (951s left) kms_chamelium_color (ctm-blue-to-red)
[66.616196] Subtest ctm-blue-to-red: SKIP (0.000s)
[66.662873] [007/131] (951s left) gem_workarounds (suspend-resume)
[66.784494] Starting subtest: suspend-resume
[83.037643] Subtest suspend-resume: SUCCESS (5.573s)
[84.195829] [008/131] (935s left) kms_plane_scaling (planes-downscale-factor-0-75-unity-scaling)
[84.321535] Starting subtest: planes-downscale-factor-0-75-unity-scaling
[84.323958] Starting dynamic subtest: pipe-A
[84.387621] Dynamic subtest pipe-A: SUCCESS (0.066s)
[84.399922] Starting dynamic subtest: pipe-B
[85.531018] Dynamic subtest pipe-B: SUCCESS (1.131s)
[85.542147] Starting dynamic subtest: pipe-C
[86.655924] Dynamic subtest pipe-C: SUCCESS (1.114s)
[86.669902] Starting dynamic subtest: pipe-D
[87.794423] Dynamic subtest pipe-D: SUCCESS (1.129s)
[87.796780] Subtest planes-downscale-factor-0-75-unity-scaling: SUCCESS (3.442s)
[88.989062] [009/131] (930s left) gem_pxp (create-regular-buffer)
[89.128452] Starting subtest: create-regular-buffer
[89.130944] Subtest create-regular-buffer: SUCCESS (0.000s)
[89.265795] [010/131] (930s left) gem_exec_params (rel-constants-invalid-rel-gen5)
[89.332866] Starting subtest: rel-constants-invalid-rel-gen5
[89.335234] Subtest rel-constants-invalid-rel-gen5: SUCCESS (0.000s)
[89.477090] [011/131] (929s left) gem_exec_nop (basic-series)
[89.553300] Starting subtest: basic-series
[105.614233] Subtest basic-series: SUCCESS (16.024s)
[105.861189] [012/131] (913s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-wc)
[105.965396] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-wc
[105.969499] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[106.097927] [013/131] (913s left) kms_psr (fbc-psr-primary-page-flip)
[106.187139] Starting subtest: fbc-psr-primary-page-flip
[106.189049] Starting dynamic subtest: eDP-1
[106.193148] Dynamic subtest eDP-1: SKIP (0.000s)
[106.197286] Subtest fbc-psr-primary-page-flip: SKIP (0.000s)
[106.281396] [014/131] (913s left) i915_pm_rps (engine-order)
[106.344577] Starting subtest: engine-order
[111.249062] Subtest engine-order: SUCCESS (4.900s)
[111.427857] [015/131] (908s left) gem_softpin (evict-active)
[111.493783] Starting subtest: evict-active
[111.506129] Subtest evict-active: SUCCESS (0.012s)
[111.653735] [016/131] (907s left) syncobj_timeline (wait-delayed-signal)
[111.715525] Starting subtest: wait-delayed-signal
[111.820633] Subtest wait-delayed-signal: SUCCESS (0.103s)
[111.930352] [017/131] (907s left) kms_panel_fitting (atomic-fastset)
[112.033845] Starting subtest: atomic-fastset
[112.036149] Starting dynamic subtest: pipe-A-eDP-1
[112.172175] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.140s)
[112.183981] Starting dynamic subtest: pipe-B-eDP-1
[113.374666] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.201s)
[113.386975] Starting dynamic subtest: pipe-C-eDP-1
[114.558877] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.184s)
[114.568256] Starting dynamic subtest: pipe-D-eDP-1
[115.707037] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.147s)
[115.709478] Subtest atomic-fastset: SUCCESS (3.674s)
[116.977394] [018/131] (902s left) kms_big_fb (x-tiled-16bpp-rotate-270)
[117.089129] Starting subtest: x-tiled-16bpp-rotate-270
[117.109590] Subtest x-tiled-16bpp-rotate-270: SKIP (0.019s)
[117.270070] [019/131] (902s left) api_intel_bb (render-ccs)
[117.333549] Starting subtest: render-ccs
[117.690225] Subtest render-ccs: SUCCESS (0.357s)
[117.839966] [020/131] (901s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc)
[117.930691] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc
[117.933044] Starting dynamic subtest: pipe-A-eDP-1
[117.955788] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[117.965537] Starting dynamic subtest: pipe-B-eDP-1
[117.986507] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[117.994992] Starting dynamic subtest: pipe-C-eDP-1
[118.016570] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[118.024826] Starting dynamic subtest: pipe-D-eDP-1
[118.046715] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[118.051023] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc: SKIP (0.006s)
[120.167528] [021/131] (899s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted-signaled)
[120.247491] Starting subtest: multi-wait-all-for-submit-unsubmitted-signaled
[120.349429] Subtest multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.101s)
[120.452954] [022/131] (899s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-move)
[120.555474] Starting subtest: psr-2p-primscrn-cur-indfb-move
[120.559821] Subtest psr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[120.668893] [023/131] (899s left) gem_lmem_swapping (heavy-multi)
[120.714242] Subtest heavy-multi: SKIP (0.000s)
[120.756146] [024/131] (899s left) kms_async_flips (test-cursor)
[120.855593] Starting subtest: test-cursor
[120.859927] Subtest test-cursor: SKIP (0.000s)
[122.088119] [025/131] (897s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-sf)
[127.125651] Starting subtest: fbc-psr2-overlay-plane-move-continuous-exceed-sf
[127.128075] Starting dynamic subtest: pipe-A-eDP-1
[127.937837] Dynamic subtest pipe-A-eDP-1: SKIP (0.809s)
[127.947413] Starting dynamic subtest: pipe-B-eDP-1
[129.074238] Dynamic subtest pipe-B-eDP-1: SKIP (1.133s)
[129.078932] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-sf: SKIP (1.946s)
[130.275460] [026/131] (889s left) kms_plane_multiple (2x-tiling-4)
[130.384981] Starting subtest: 2x-tiling-4
[130.389329] Subtest 2x-tiling-4: SKIP (0.000s)
[130.481851] [027/131] (889s left) gem_ctx_param (invalid-param-set)
[130.529769] Starting subtest: invalid-param-set
[130.531972] Subtest invalid-param-set: SUCCESS (0.000s)
[130.631090] [028/131] (889s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[130.722031] Starting subtest: bad-pixel-format-y-tiled-ccs
[130.724312] Starting dynamic subtest: pipe-A-eDP-1
[130.747396] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[130.757489] Starting dynamic subtest: pipe-B-eDP-1
[130.779623] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[130.788888] Starting dynamic subtest: pipe-C-eDP-1
[130.810815] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[130.819763] Starting dynamic subtest: pipe-D-eDP-1
[130.842047] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[130.846234] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.003s)
[133.050360] [029/131] (887s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[133.147369] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[133.149587] Starting dynamic subtest: pipe-A
[133.167115] Dynamic subtest pipe-A: SKIP (0.016s)
[133.177251] Starting dynamic subtest: pipe-B
[133.199387] Dynamic subtest pipe-B: SKIP (0.031s)
[133.208391] Starting dynamic subtest: pipe-C
[133.230629] Dynamic subtest pipe-C: SKIP (0.031s)
[133.239641] Starting dynamic subtest: pipe-D
[133.261327] Dynamic subtest pipe-D: SKIP (0.031s)
[133.265558] Subtest plane-downscale-factor-0-5-with-modifiers: SKIP (0.110s)
[133.369833] [030/131] (886s left) syncobj_timeline (multi-wait-all-available-submitted-signaled)
[133.435295] Starting subtest: multi-wait-all-available-submitted-signaled
[133.437617] Subtest multi-wait-all-available-submitted-signaled: SUCCESS (0.000s)
[133.572967] [031/131] (886s left) perf (missing-sample-flags)
[133.714160] Starting subtest: missing-sample-flags
[133.716031] Subtest missing-sample-flags: SUCCESS (0.001s)
[133.760924] [032/131] (886s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-pwrite)
[133.858803] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-pwrite
[140.053035] Subtest fbc-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (6.196s)
[141.251396] [033/131] (879s left) kms_pm_rpm (system-suspend-idle)
[142.802183] Starting subtest: system-suspend-idle
[162.553094] Subtest system-suspend-idle: SUCCESS (4.605s)
[162.978553] [034/131] (857s left) kms_vblank (wait-forked)
[163.093747] Starting subtest: wait-forked
[163.095718] Starting dynamic subtest: pipe-A-eDP-1
[164.917556] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.809s)
[164.932522] Starting dynamic subtest: pipe-D-eDP-1
[167.475056] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.537s)
[167.477291] Subtest wait-forked: SUCCESS (4.350s)
[168.361944] [035/131] (852s left) kms_big_fb (y-tiled-32bpp-rotate-0)
[168.475520] Starting subtest: y-tiled-32bpp-rotate-0
[168.479858] Subtest y-tiled-32bpp-rotate-0: SKIP (0.000s)
[168.615368] [036/131] (851s left) kms_pipe_crc_basic (read-crc)
[168.722958] Starting subtest: read-crc
[168.724910] Starting dynamic subtest: pipe-A-eDP-1
[169.442257] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.715s)
[169.452621] Starting dynamic subtest: pipe-B-eDP-1
[170.835527] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.386s)
[170.845737] Starting dynamic subtest: pipe-C-eDP-1
[172.233719] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.393s)
[172.244440] Starting dynamic subtest: pipe-D-eDP-1
[173.570022] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.331s)
[173.572796] Subtest read-crc: SUCCESS (4.831s)
[174.421896] [037/131] (846s left) kms_cursor_legacy (single-move)
[174.554751] Starting subtest: single-move
[174.559427] Starting dynamic subtest: pipe-A
[179.947341] Dynamic subtest pipe-A: SUCCESS (5.379s)
[179.959443] Starting dynamic subtest: pipe-B
[185.336253] Dynamic subtest pipe-B: SUCCESS (5.381s)
[185.347505] Starting dynamic subtest: pipe-C
[190.723046] Dynamic subtest pipe-C: SUCCESS (5.381s)
[190.734270] Starting dynamic subtest: pipe-D
[196.105475] Dynamic subtest pipe-D: SUCCESS (5.380s)
[196.116877] Starting dynamic subtest: all-pipes
[201.493664] Dynamic subtest all-pipes: SUCCESS (5.387s)
[201.496150] Subtest single-move: SUCCESS (26.915s)
[201.663446] [038/131] (818s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-sf)
[206.600714] Starting subtest: pr-overlay-plane-move-continuous-exceed-sf
[206.605201] Subtest pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.003s)
[207.476503] [039/131] (813s left) gem_unref_active_buffers
[207.709617] [040/131] (812s left) kms_psr (fbc-psr2-primary-mmap-gtt)
[207.804731] Starting subtest: fbc-psr2-primary-mmap-gtt
[207.807269] Starting dynamic subtest: eDP-1
[207.811593] Dynamic subtest eDP-1: SKIP (0.000s)
[207.815904] Subtest fbc-psr2-primary-mmap-gtt: SKIP (0.001s)
[207.903408] [041/131] (812s left) syncobj_wait (invalid-signal-zero-handles)
[207.960316] Starting subtest: invalid-signal-zero-handles
[207.962284] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[208.072517] [042/131] (812s left) gen9_exec_parse (bb-oversize)
[208.120035] Subtest bb-oversize: SKIP (0.000s)
[208.197585] [043/131] (812s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted)
[208.251962] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted
[208.359736] Subtest etime-multi-wait-all-for-submit-available-unsubmitted: SUCCESS (0.107s)
[208.457432] [044/131] (812s left) gem_exec_reloc (basic-write-cpu-noreloc)
[208.524900] Subtest basic-write-cpu-noreloc: SKIP (0.000s)
[208.600952] [045/131] (812s left) gem_mmap_offset (bad-flags)
[208.651176] Starting subtest: bad-flags
[208.653151] Subtest bad-flags: SUCCESS (0.000s)
[208.791426] [046/131] (812s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[208.934718] Starting subtest: cursor-vs-flip-legacy
[238.272722] Subtest cursor-vs-flip-legacy: SUCCESS (29.334s)
[239.105230] [047/131] (781s left) kms_rotation_crc (sprite-rotation-90-pos-100-0)
[239.202558] Starting subtest: sprite-rotation-90-pos-100-0
[239.206845] Subtest sprite-rotation-90-pos-100-0: SKIP (0.001s)
[239.297701] [048/131] (781s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[239.390032] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip
[240.401012] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip: SUCCESS (1.013s)
[240.579400] [049/131] (780s left) gem_create (busy-create)
[240.636191] Starting subtest: busy-create
[240.638418] Starting dynamic subtest: smem0
[273.740557] Dynamic subtest smem0: SUCCESS (33.096s)
[273.743378] Subtest busy-create: SUCCESS (33.098s)
[273.948843] [050/131] (747s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-render)
[274.054294] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-render
[278.266593] Subtest fbc-1p-offscreen-pri-indfb-draw-render: SUCCESS (4.214s)
[279.419158] [051/131] (741s left) perf (invalid-open-flags)
[279.531580] Starting subtest: invalid-open-flags
[279.534086] Subtest invalid-open-flags: SUCCESS (0.000s)
[279.578599] [052/131] (741s left) perf (oa-formats)
[279.682509] Starting subtest: oa-formats
[279.684937] Starting dynamic subtest: 0-rcs0
[279.698472] Dynamic subtest 0-rcs0: SUCCESS (0.016s)
[279.708830] Starting dynamic subtest: 1-vecs0
[279.720707] Dynamic subtest 1-vecs0: SUCCESS (0.021s)
[279.722992] Subtest oa-formats: SUCCESS (0.038s)
[279.764225] [053/131] (741s left) kms_big_fb (4-tiled-8bpp-rotate-270)
[279.854438] Starting subtest: 4-tiled-8bpp-rotate-270
[279.875715] Subtest 4-tiled-8bpp-rotate-270: SKIP (0.021s)
[280.035354] [054/131] (741s left) kms_cursor_crc (cursor-onscreen-64x21)
[280.235524] Starting subtest: cursor-onscreen-64x21
[280.285720] Subtest cursor-onscreen-64x21: SKIP (0.044s)
[280.519676] [055/131] (740s left) kms_rotation_crc (primary-4-tiled-reflect-x-180)
[280.614789] Starting subtest: primary-4-tiled-reflect-x-180
[287.683105] Subtest primary-4-tiled-reflect-x-180: SUCCESS (7.067s)
[288.883956] [056/131] (732s left) kms_cursor_crc (cursor-onscreen-256x256)
[289.103484] Starting subtest: cursor-onscreen-256x256
[289.108709] Starting dynamic subtest: pipe-A-eDP-1
[291.194358] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.085s)
[291.224288] Starting dynamic subtest: pipe-D-eDP-1
[293.966504] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.748s)
[293.968883] Subtest cursor-onscreen-256x256: SUCCESS (4.860s)
[294.853277] [057/131] (726s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-msflip-blt)
[294.956931] Starting subtest: fbcpsr-1p-primscrn-shrfb-msflip-blt
[302.360768] Subtest fbcpsr-1p-primscrn-shrfb-msflip-blt: SUCCESS (7.404s)
[302.522120] [058/131] (718s left) kms_async_flips (async-flip-with-page-flip-events-tiled)
[302.631367] Starting subtest: async-flip-with-page-flip-events-tiled
[303.029843] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs
[305.739128] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: SUCCESS (2.707s)
[305.748513] Starting dynamic subtest: pipe-A-eDP-1-4
[307.914336] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.173s)
[307.923776] Starting dynamic subtest: pipe-A-eDP-1-4
[309.924829] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.008s)
[309.934744] Starting dynamic subtest: pipe-A-eDP-1-x
[312.115789] Dynamic subtest pipe-A-eDP-1-x: SUCCESS (2.189s)
[312.500632] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs
[315.272312] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: SUCCESS (2.777s)
[315.282273] Starting dynamic subtest: pipe-B-eDP-1-4
[317.459405] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.185s)
[317.468739] Starting dynamic subtest: pipe-B-eDP-1-4
[319.469729] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.009s)
[319.479855] Starting dynamic subtest: pipe-B-eDP-1-x
[321.649958] Dynamic subtest pipe-B-eDP-1-x: SUCCESS (2.178s)
[322.023166] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs
[324.805040] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.787s)
[324.815366] Starting dynamic subtest: pipe-C-eDP-1-4
[326.991425] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.185s)
[327.001386] Starting dynamic subtest: pipe-C-eDP-1-4
[329.001568] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.009s)
[329.011341] Starting dynamic subtest: pipe-C-eDP-1-x
[331.193457] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.189s)
[331.557446] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs
[334.325864] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.774s)
[334.338755] Starting dynamic subtest: pipe-D-eDP-1-4
[336.500192] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.174s)
[336.509744] Starting dynamic subtest: pipe-D-eDP-1-4
[338.510671] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.009s)
[338.520715] Starting dynamic subtest: pipe-D-eDP-1-x
[340.691191] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.178s)
[340.693643] Subtest async-flip-with-page-flip-events-tiled: SUCCESS (38.046s)
[341.937203] [059/131] (679s left) perf (polling)
[342.031461] Starting subtest: polling
[342.033940] Starting dynamic subtest: 0-rcs0
[352.047110] Dynamic subtest 0-rcs0: SUCCESS (10.010s)
[352.057630] Starting dynamic subtest: 1-vcs1
[362.062719] Dynamic subtest 1-vcs1: SUCCESS (10.017s)
[362.065333] Subtest polling: SUCCESS (20.030s)
[362.110861] [060/131] (659s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[362.223398] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[362.227812] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[362.360914] [061/131] (659s left) syncobj_timeline (reset-signaled)
[362.445225] Starting subtest: reset-signaled
[362.447486] Subtest reset-signaled: SUCCESS (0.001s)
[362.578234] [062/131] (658s left) kms_rmfb (rmfb-ioctl)
[362.681969] Starting subtest: rmfb-ioctl
[362.684212] Starting dynamic subtest: pipe-A-eDP-1
[362.786331] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.105s)
[362.798205] Starting dynamic subtest: pipe-B-eDP-1
[363.972374] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.185s)
[363.984559] Starting dynamic subtest: pipe-C-eDP-1
[365.090974] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.117s)
[365.100586] Starting dynamic subtest: pipe-D-eDP-1
[366.221726] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.129s)
[366.224035] Subtest rmfb-ioctl: SUCCESS (3.545s)
[367.458409] [063/131] (654s left) gem_userptr_blits (create-destroy-sync)
[367.534219] Starting subtest: create-destroy-sync
[372.552376] Subtest create-destroy-sync: SUCCESS (5.019s)
[372.739194] [064/131] (648s left) gem_exec_reloc (basic-gtt-cpu-active)
[372.806497] Subtest basic-gtt-cpu-active: SKIP (0.000s)
[372.886394] [065/131] (648s left) api_intel_allocator (execbuf-with-allocator)
[372.943684] Starting subtest: execbuf-with-allocator
[372.949473] Subtest execbuf-with-allocator: SUCCESS (0.008s)
[373.108029] [066/131] (648s left) kms_lease (cursor-implicit-plane)
[373.207837] Starting subtest: cursor-implicit-plane
[373.210187] Starting dynamic subtest: pipe-A-eDP-1
[373.653751] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.445s)
[373.663951] Starting dynamic subtest: pipe-B-eDP-1
[374.745833] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.092s)
[374.755937] Starting dynamic subtest: pipe-C-eDP-1
[375.863243] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.113s)
[375.873991] Starting dynamic subtest: pipe-D-eDP-1
[376.984565] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.122s)
[376.987201] Subtest cursor-implicit-plane: SUCCESS (3.780s)
[377.877861] [067/131] (643s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions-varying-size)
[377.994812] Starting subtest: cursorA-vs-flipB-atomic-transitions-varying-size
[377.999372] Subtest cursorA-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[378.166950] [068/131] (643s left) syncobj_wait (invalid-signal-illegal-handle)
[378.233930] Starting subtest: invalid-signal-illegal-handle
[378.235800] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[378.376059] [069/131] (643s left) kms_psr (psr-primary-blt)
[378.473062] Starting subtest: psr-primary-blt
[378.475212] Starting dynamic subtest: eDP-1
[380.163928] Dynamic subtest eDP-1: SUCCESS (1.690s)
[380.165886] Subtest psr-primary-blt: SUCCESS (1.691s)
[381.053016] [070/131] (640s left) kms_ccs (crc-sprite-planes-basic-y-tiled-gen12-rc-ccs)
[381.148785] Starting subtest: crc-sprite-planes-basic-y-tiled-gen12-rc-ccs
[381.151070] Starting dynamic subtest: pipe-A-eDP-1
[381.210932] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[381.220589] Starting dynamic subtest: pipe-B-eDP-1
[381.276932] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[381.288039] Starting dynamic subtest: pipe-C-eDP-1
[381.345118] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[381.356398] Starting dynamic subtest: pipe-D-eDP-1
[381.415483] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[381.419717] Subtest crc-sprite-planes-basic-y-tiled-gen12-rc-ccs: SKIP (0.009s)
[383.323064] [071/131] (638s left) kms_psr (pr-primary-blt)
[383.427036] Starting subtest: pr-primary-blt
[383.431333] Subtest pr-primary-blt: SKIP (0.000s)
[383.523963] [072/131] (638s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-move)
[383.616865] Starting subtest: psr-2p-primscrn-spr-indfb-move
[383.620763] Subtest psr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[383.754458] [073/131] (638s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[383.858529] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[383.860675] Starting dynamic subtest: pipe-A-default-mode
[385.061877] Dynamic subtest pipe-A-default-mode: SKIP (1.200s)
[385.066286] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SKIP (1.202s)
[385.262488] [074/131] (636s left) gem_exec_schedule (u-semaphore-noskip)
[385.342410] Starting subtest: u-semaphore-noskip
[385.730642] Subtest u-semaphore-noskip: SUCCESS (0.395s)
[386.097425] [075/131] (636s left) i915_drm_fdinfo (memory-info-idle)
[386.140580] Starting subtest: memory-info-idle
[386.143455] Starting dynamic subtest: smem0
[386.202158] Dynamic subtest smem0: SUCCESS (0.063s)
[386.204630] Subtest memory-info-idle: SUCCESS (0.064s)
[386.303764] [076/131] (635s left) i915_selftest (live)
[389.923327] Starting subtest: live
[389.925818] Starting dynamic subtest: sanitycheck
[392.841420] Dynamic subtest sanitycheck: SUCCESS (2.918s)
[392.852749] Starting dynamic subtest: uncore
[397.348783] Dynamic subtest uncore: SUCCESS (4.505s)
[397.359169] Starting dynamic subtest: workarounds
[415.815512] Dynamic subtest workarounds: FAIL (18.353s)
[415.824622] Starting dynamic subtest: gt_engines
[420.537619] Dynamic subtest gt_engines: SUCCESS (4.831s)
[420.547949] Starting dynamic subtest: gt_timelines
[436.936303] Dynamic subtest gt_timelines: SUCCESS (16.395s)
[436.946920] Starting dynamic subtest: gt_contexts
[441.435589] Dynamic subtest gt_contexts: SUCCESS (4.497s)
[441.445723] Starting dynamic subtest: gt_lrc
[447.720102] Dynamic subtest gt_lrc: SUCCESS (6.281s)
[447.730163] Starting dynamic subtest: gt_mocs
[450.888047] Dynamic subtest gt_mocs: SUCCESS (3.166s)
[450.902365] Starting dynamic subtest: gt_pm
[457.797995] Dynamic subtest gt_pm: SUCCESS (6.906s)
[457.808338] Starting dynamic subtest: gt_heartbeat
[462.336185] Dynamic subtest gt_heartbeat: SUCCESS (4.535s)
[462.345534] Starting dynamic subtest: gt_tlb
[472.499288] Dynamic subtest gt_tlb: SUCCESS (10.159s)
[472.509167] Starting dynamic subtest: requests
[484.775391] Dynamic subtest requests: SUCCESS (12.272s)
[484.790125] Starting dynamic subtest: migrate
[492.964136] Dynamic subtest migrate: SUCCESS (8.185s)
[492.974716] Starting dynamic subtest: active
[495.782955] Dynamic subtest active: SUCCESS (2.816s)
[495.798442] Starting dynamic subtest: objects
[499.159948] Dynamic subtest objects: SUCCESS (3.374s)
[499.170345] Starting dynamic subtest: mman
[504.119717] Dynamic subtest mman: SUCCESS (4.958s)
[504.130935] Starting dynamic subtest: dmabuf
[508.686118] Dynamic subtest dmabuf: SUCCESS (4.562s)
[508.696260] Starting dynamic subtest: vma
[513.199940] Dynamic subtest vma: SUCCESS (4.511s)
[513.216073] Starting dynamic subtest: coherency
[518.291193] Dynamic subtest coherency: SUCCESS (5.087s)
[518.301166] Starting dynamic subtest: gtt
[530.708531] Dynamic subtest gtt: SUCCESS (12.413s)
[530.720615] Starting dynamic subtest: gem
[538.533719] Dynamic subtest gem: SUCCESS (7.822s)
[538.543848] Starting dynamic subtest: evict
[542.680187] Dynamic subtest evict: SUCCESS (4.144s)
[542.690426] Starting dynamic subtest: hugepages
[554.288053] Dynamic subtest hugepages: SUCCESS (11.603s)
[554.297566] Starting dynamic subtest: gem_contexts
[581.955394] Dynamic subtest gem_contexts: SUCCESS (27.658s)
[581.970579] Starting dynamic subtest: client
[586.434385] Dynamic subtest client: SUCCESS (4.477s)
[586.444533] Starting dynamic subtest: gem_migrate
[590.941024] Dynamic subtest gem_migrate: SUCCESS (4.503s)
[590.951596] Starting dynamic subtest: reset
[595.419925] Dynamic subtest reset: SUCCESS (4.477s)
[595.435844] Starting dynamic subtest: memory_region
[599.891439] Dynamic subtest memory_region: SUCCESS (4.467s)
[599.900359] Starting dynamic subtest: hangcheck
[613.928474] Dynamic subtest hangcheck: SUCCESS (14.031s)
[613.944207] Starting dynamic subtest: execlists
[618.361108] Dynamic subtest execlists: SUCCESS (4.429s)
[618.370904] Starting dynamic subtest: ring_submission
[622.896138] Dynamic subtest ring_submission: SUCCESS (4.531s)
[622.906487] Starting dynamic subtest: perf
[627.371357] Dynamic subtest perf: SUCCESS (4.472s)
[627.381716] Starting dynamic subtest: slpc
[641.975295] Dynamic subtest slpc: SUCCESS (14.599s)
[641.982950] Starting dynamic subtest: guc
[646.028999] Dynamic subtest guc: SUCCESS (4.051s)
[646.039198] Starting dynamic subtest: guc_multi_lrc
[650.543650] Dynamic subtest guc_multi_lrc: SUCCESS (4.511s)
[650.553922] Starting dynamic subtest: guc_hang
[655.608699] Dynamic subtest guc_hang: SUCCESS (5.061s)
[655.624782] Starting dynamic subtest: late_gt_pm
[660.088570] Dynamic subtest late_gt_pm: SUCCESS (4.476s)
[660.091160] Subtest live: FAIL (270.113s)
[660.120322] [FACT i915_selftest (live)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[660.121078] [FACT i915_selftest (live)] deleted: kernel.kmod_is_loaded.i915: true
[660.123679] [077/131] (362s left) gem_userptr_blits (nohangcheck)
[661.729518] Starting subtest: nohangcheck
[661.985387] Subtest nohangcheck: SUCCESS (0.255s)
[662.079906] [FACT gem_userptr_blits (nohangcheck)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[662.081115] [FACT gem_userptr_blits (nohangcheck)] new: kernel.kmod_is_loaded.i915: true
[662.084549] [078/131] (360s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs)
[662.174063] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs
[662.175989] Starting dynamic subtest: pipe-A-eDP-1
[663.007521] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.826s)
[663.017675] Starting dynamic subtest: pipe-B-eDP-1
[664.636123] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.626s)
[664.646156] Starting dynamic subtest: pipe-C-eDP-1
[666.102898] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.465s)
[666.114386] Starting dynamic subtest: pipe-D-eDP-1
[667.652257] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.549s)
[667.654134] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs: SUCCESS (5.471s)
[668.847280] [079/131] (353s left) gem_softpin (allocator-nopin)
[668.919738] Starting subtest: allocator-nopin
[669.009427] Subtest allocator-nopin: SUCCESS (0.091s)
[669.181554] [080/131] (353s left) kms_draw_crc (draw-method-pwrite)
[669.270831] Starting subtest: draw-method-pwrite
[669.273047] Starting dynamic subtest: xrgb8888-untiled
[669.826979] Dynamic subtest xrgb8888-untiled: SUCCESS (0.556s)
[669.836223] Starting dynamic subtest: rgb565-untiled
[670.259738] Dynamic subtest rgb565-untiled: SUCCESS (0.433s)
[670.271876] Starting dynamic subtest: xrgb2101010-untiled
[670.816509] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.553s)
[670.826163] Starting dynamic subtest: xbgr16161616f-untiled
[671.562700] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.744s)
[671.572052] Starting dynamic subtest: xrgb8888-xtiled
[673.316694] Dynamic subtest xrgb8888-xtiled: SUCCESS (1.755s)
[673.329908] Starting dynamic subtest: rgb565-xtiled
[674.395764] Dynamic subtest rgb565-xtiled: SUCCESS (1.075s)
[674.405361] Starting dynamic subtest: xrgb2101010-xtiled
[676.161633] Dynamic subtest xrgb2101010-xtiled: SUCCESS (1.766s)
[676.176421] Starting dynamic subtest: xbgr16161616f-xtiled
[679.362285] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (3.198s)
[679.372045] Starting dynamic subtest: xrgb8888-4tiled
[680.440324] Dynamic subtest xrgb8888-4tiled: SUCCESS (1.077s)
[680.450505] Starting dynamic subtest: rgb565-4tiled
[680.985245] Dynamic subtest rgb565-4tiled: SUCCESS (0.544s)
[680.994640] Starting dynamic subtest: xrgb2101010-4tiled
[682.085181] Dynamic subtest xrgb2101010-4tiled: SUCCESS (1.099s)
[682.094555] Starting dynamic subtest: xbgr16161616f-4tiled
[685.053109] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (2.966s)
[685.055517] Subtest draw-method-pwrite: SUCCESS (15.779s)
[685.161854] [081/131] (337s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[685.284882] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip
[685.289246] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[685.416368] [082/131] (336s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-plflip-blt)
[685.522931] Starting subtest: fbc-2p-primscrn-indfb-plflip-blt
[685.526979] Subtest fbc-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[685.640989] [083/131] (336s left) syncobj_timeline (host-signal-points)
[685.706161] Starting subtest: host-signal-points
[685.711282] Subtest host-signal-points: SUCCESS (0.004s)
[685.842447] [084/131] (336s left) gem_mmap_gtt (basic-small-bo)
[685.904220] Subtest basic-small-bo: SKIP (0.000s)
[685.966909] [085/131] (336s left) perf_pmu (semaphore-wait-idle)
[686.011441] Starting subtest: semaphore-wait-idle
[686.013219] Starting dynamic subtest: rcs0
[686.526408] Dynamic subtest rcs0: SUCCESS (0.513s)
[686.537752] Starting dynamic subtest: bcs0
[687.040187] Dynamic subtest bcs0: SUCCESS (0.510s)
[687.052674] Starting dynamic subtest: vcs0
[687.553730] Dynamic subtest vcs0: SUCCESS (0.510s)
[687.566096] Starting dynamic subtest: vcs1
[688.065400] Dynamic subtest vcs1: SUCCESS (0.510s)
[688.075642] Starting dynamic subtest: vecs0
[688.581990] Dynamic subtest vecs0: SUCCESS (0.512s)
[688.594123] Starting dynamic subtest: ccs0
[689.097958] Dynamic subtest ccs0: SUCCESS (0.512s)
[689.100691] Subtest semaphore-wait-idle: SUCCESS (3.086s)
[689.244166] [086/131] (333s left) kms_addfb_basic (no-handle)
[689.308010] Starting subtest: no-handle
[689.310705] Subtest no-handle: SUCCESS (0.000s)
[689.481102] [087/131] (333s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-pwrite)
[689.586521] Starting subtest: fbcpsr-rgb101010-draw-pwrite
[696.652889] Subtest fbcpsr-rgb101010-draw-pwrite: SUCCESS (7.066s)
[696.804697] [088/131] (325s left) gem_render_copy (yf-tiled-ccs-to-linear)
[696.881394] Starting subtest: yf-tiled-ccs-to-linear
[696.886258] Subtest yf-tiled-ccs-to-linear: SKIP (0.000s)
[697.090602] [089/131] (325s left) kms_flip (wf_vblank-ts-check)
[697.186876] Starting subtest: wf_vblank-ts-check
[697.189315] Starting dynamic subtest: A-eDP1
[706.092846] Dynamic subtest A-eDP1: SUCCESS (8.906s)
[706.103076] Starting dynamic subtest: B-eDP1
[715.021062] Dynamic subtest B-eDP1: SUCCESS (8.924s)
[715.036409] Starting dynamic subtest: C-eDP1
[723.937695] Dynamic subtest C-eDP1: SUCCESS (8.911s)
[723.947449] Starting dynamic subtest: D-eDP1
[732.869910] Dynamic subtest D-eDP1: SUCCESS (8.928s)
[732.872260] Subtest wf_vblank-ts-check: SUCCESS (35.680s)
[734.124674] [090/131] (288s left) gem_exec_schedule (deep)
[734.318523] Starting subtest: deep
[734.320937] Starting dynamic subtest: rcs0
[738.958291] Dynamic subtest rcs0: SKIP (4.635s)
[738.969871] Starting dynamic subtest: vcs0
[749.344135] Dynamic subtest vcs0: SUCCESS (10.384s)
[749.360602] Starting dynamic subtest: vcs1
[759.910693] Dynamic subtest vcs1: SUCCESS (10.561s)
[759.923579] Starting dynamic subtest: vecs0
[771.137036] Dynamic subtest vecs0: SUCCESS (11.222s)
[771.150273] Starting dynamic subtest: ccs0
[784.049247] Dynamic subtest ccs0: SUCCESS (12.907s)
[784.051373] Subtest deep: SUCCESS (49.715s)
[785.395739] [091/131] (237s left) kms_sequence (queue-idle)
[785.502060] Starting subtest: queue-idle
[785.503999] Starting dynamic subtest: pipe-A-eDP-1
[787.307545] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.806s)
[787.318069] Starting dynamic subtest: pipe-B-eDP-1
[789.773723] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.463s)
[789.784639] Starting dynamic subtest: pipe-C-eDP-1
[792.203282] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.427s)
[792.213271] Starting dynamic subtest: pipe-D-eDP-1
[794.629362] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.426s)
[794.631832] Subtest queue-idle: SUCCESS (9.127s)
[795.477046] [092/131] (227s left) gem_gtt_cpu_tlb
[795.600621] [093/131] (227s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[795.709863] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc
[802.302832] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.600s)
[802.461768] [094/131] (220s left) gem_eio (create-ext)
[802.711095] Starting subtest: create-ext
[803.039843] Subtest create-ext: SUCCESS (0.331s)
[803.210059] [095/131] (219s left) gem_partial_pwrite_pread (writes-after-reads-uncached)
[803.272902] Subtest writes-after-reads-uncached: SKIP (0.000s)
[803.356088] [096/131] (219s left) sysfs_preempt_timeout (idempotent)
[803.414969] Starting subtest: idempotent
[803.416805] Starting dynamic subtest: vecs0
[803.418709] Dynamic subtest vecs0: SUCCESS (0.000s)
[803.428613] Starting dynamic subtest: ccs0
[803.430225] Dynamic subtest ccs0: SUCCESS (0.000s)
[803.439453] Starting dynamic subtest: vcs0
[803.441006] Dynamic subtest vcs0: SUCCESS (0.000s)
[803.450174] Starting dynamic subtest: bcs0
[803.451735] Dynamic subtest bcs0: SUCCESS (0.000s)
[803.460585] Starting dynamic subtest: rcs0
[803.462170] Dynamic subtest rcs0: SUCCESS (0.000s)
[803.471598] Starting dynamic subtest: vcs1
[803.473566] Dynamic subtest vcs1: SUCCESS (0.000s)
[803.475451] Subtest idempotent: SUCCESS (0.007s)
[803.553905] [097/131] (219s left) gem_busy (semaphore)
[803.749925] Subtest semaphore: SKIP (0.000s)
[804.022972] [098/131] (219s left) sriov_basic (enable-vfs-bind-unbind-each)
[804.087156] Starting subtest: enable-vfs-bind-unbind-each
[804.089266] Starting dynamic subtest: numvfs-1
[804.164144] Dynamic subtest numvfs-1: FAIL (0.005s)
[804.180436] Starting dynamic subtest: numvfs-2
[804.254179] Dynamic subtest numvfs-2: FAIL (0.004s)
[804.265450] Starting dynamic subtest: numvfs-3
[804.338373] Dynamic subtest numvfs-3: FAIL (0.004s)
[804.350111] Starting dynamic subtest: numvfs-4
[804.424143] Dynamic subtest numvfs-4: FAIL (0.003s)
[804.436437] Starting dynamic subtest: numvfs-5
[804.510299] Dynamic subtest numvfs-5: FAIL (0.004s)
[804.521091] Starting dynamic subtest: numvfs-6
[804.593502] Dynamic subtest numvfs-6: FAIL (0.004s)
[804.604368] Starting dynamic subtest: numvfs-7
[804.685173] Dynamic subtest numvfs-7: FAIL (0.004s)
[804.696113] Starting dynamic subtest: numvfs-random
[804.768578] Dynamic subtest numvfs-random: FAIL (0.004s)
[804.770913] Subtest enable-vfs-bind-unbind-each: FAIL (0.037s)
[804.815057] [099/131] (218s left) kms_pm_lpsp (screens-disabled)
[805.923038] Starting subtest: screens-disabled
[805.927492] Subtest screens-disabled: SKIP (0.000s)
[806.021105] [100/131] (217s left) gem_exec_capture (capture-invisible)
[806.094016] Starting subtest: capture-invisible
[806.096364] Starting dynamic subtest: smem0
[806.100827] Dynamic subtest smem0: SKIP (0.000s)
[806.105263] Subtest capture-invisible: SKIP (0.001s)
[806.227542] [101/131] (216s left) gem_lmem_swapping (verify-ccs)
[806.269586] Subtest verify-ccs: SKIP (0.000s)
[806.309490] [102/131] (216s left) kms_joiner (basic-force-ultra-joiner)
[806.416491] Starting subtest: basic-force-ultra-joiner
[806.420619] Subtest basic-force-ultra-joiner: SKIP (0.000s)
[806.529113] [103/131] (216s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-wc)
[806.649510] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-wc
[813.154382] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.537s)
[813.292860] [104/131] (210s left) kms_color (legacy-gamma)
[813.379852] Starting subtest: legacy-gamma
[813.381689] Starting dynamic subtest: pipe-A-eDP-1
[814.085326] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.710s)
[814.096210] Starting dynamic subtest: pipe-B-eDP-1
[815.547379] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.465s)
[815.560295] Starting dynamic subtest: pipe-C-eDP-1
[816.917843] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.375s)
[816.927962] Starting dynamic subtest: pipe-D-eDP-1
[818.236800] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.321s)
[818.239122] Subtest legacy-gamma: SUCCESS (4.874s)
[819.080850] [105/131] (204s left) gem_exec_schedule (wide)
[819.260941] Starting subtest: wide
[819.263522] Starting dynamic subtest: rcs0
[829.396791] Dynamic subtest rcs0: SUCCESS (10.156s)
[829.412214] Starting dynamic subtest: vcs0
[840.181731] Dynamic subtest vcs0: SUCCESS (10.799s)
[840.196367] Starting dynamic subtest: vcs1
[850.297334] Dynamic subtest vcs1: SUCCESS (10.125s)
[850.309344] Starting dynamic subtest: vecs0
[862.504105] Dynamic subtest vecs0: SUCCESS (12.214s)
[862.517560] Starting dynamic subtest: ccs0
[876.250059] Dynamic subtest ccs0: SUCCESS (13.742s)
[876.252767] Subtest wide: SUCCESS (57.040s)
[878.160415] [106/131] (145s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-upscaling)
[878.265906] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-upscaling
[878.268179] Starting dynamic subtest: pipe-A-default-mode
[878.553511] Dynamic subtest pipe-A-default-mode: SKIP (0.284s)
[878.558164] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-upscaling: SKIP (0.286s)
[879.433450] [107/131] (144s left) kms_big_fb (linear-64bpp-rotate-180)
[879.540002] Starting subtest: linear-64bpp-rotate-180
[881.163263] Subtest linear-64bpp-rotate-180: SUCCESS (1.624s)
[881.362542] [108/131] (142s left) gem_ppgtt (blt-vs-render-ctxn)
[881.424279] Starting subtest: blt-vs-render-ctxN
[914.628437] Subtest blt-vs-render-ctxN: SUCCESS (33.200s)
[914.798836] [109/131] (108s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-plflip-blt)
[914.949122] Starting subtest: psr-2p-scndscrn-shrfb-plflip-blt
[914.953265] Subtest psr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[915.068400] [110/131] (108s left) kms_chamelium_frames (dp-frame-dump)
[915.462340] Subtest dp-frame-dump: SKIP (0.000s)
[916.234750] [111/131] (107s left) kms_psr2_su (page_flip-nv12)
[916.297616] Subtest page_flip-NV12: SKIP (0.000s)
[916.369777] [112/131] (107s left) gem_tiling_max_stride
[916.504724] [113/131] (107s left) perf_pmu (busy-idle)
[916.543982] Starting subtest: busy-idle
[916.546186] Starting dynamic subtest: rcs0
[917.243373] Dynamic subtest rcs0: SUCCESS (0.699s)
[917.256369] Starting dynamic subtest: bcs0
[917.916495] Dynamic subtest bcs0: SUCCESS (0.671s)
[917.930406] Starting dynamic subtest: vcs0
[919.706935] Dynamic subtest vcs0: SUCCESS (1.787s)
[919.720809] Starting dynamic subtest: vcs1
[921.691688] Dynamic subtest vcs1: SUCCESS (1.983s)
[921.706184] Starting dynamic subtest: vecs0
[923.739561] Dynamic subtest vecs0: SUCCESS (2.045s)
[923.753628] Starting dynamic subtest: ccs0
[924.414714] Dynamic subtest ccs0: SUCCESS (0.674s)
[924.417224] Subtest busy-idle: SUCCESS (7.874s)
[924.535126] [114/131] ( 99s left) dmabuf_sync_file (export-before-signal)
[924.576233] Starting subtest: export-before-signal
[924.578061] Subtest export-before-signal: SUCCESS (0.001s)
[924.680391] [115/131] ( 99s left) gem_exec_reloc (basic-cpu-read)
[924.730247] Subtest basic-cpu-read: SKIP (0.000s)
[924.819926] [116/131] ( 99s left) kms_hdr (bpc-switch)
[924.920838] Starting subtest: bpc-switch
[926.015912] Starting dynamic subtest: pipe-A-eDP-1
[929.523275] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.509s)
[929.525701] Subtest bpc-switch: SUCCESS (4.607s)
[929.652867] [117/131] ( 94s left) perf (i915-ref-count)
[929.775012] Starting subtest: i915-ref-count
[929.842628] Subtest i915-ref-count: SUCCESS (0.065s)
[929.899620] [118/131] ( 94s left) gem_ctx_isolation (nonpriv)
[929.966057] Starting subtest: nonpriv
[929.968452] Starting dynamic subtest: rcs0
[930.234317] Dynamic subtest rcs0: SUCCESS (0.268s)
[930.245766] Starting dynamic subtest: bcs0
[930.517442] Dynamic subtest bcs0: SUCCESS (0.282s)
[930.528279] Starting dynamic subtest: vcs0
[930.797738] Dynamic subtest vcs0: SUCCESS (0.279s)
[930.810853] Starting dynamic subtest: vcs1
[931.965448] Dynamic subtest vcs1: SUCCESS (1.167s)
[931.979011] Starting dynamic subtest: vecs0
[933.991282] Dynamic subtest vecs0: SUCCESS (2.025s)
[934.002500] Starting dynamic subtest: ccs0
[935.999818] Dynamic subtest ccs0: SUCCESS (2.008s)
[936.002100] Subtest nonpriv: SUCCESS (6.035s)
[936.269985] [119/131] ( 87s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[936.340570] Starting subtest: y-tiled-to-vebox-x-tiled
[936.345348] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[936.554398] [120/131] ( 87s left) kms_atomic_transition (plane-use-after-nonblocking-unbind)
[936.646917] Starting subtest: plane-use-after-nonblocking-unbind
[936.649171] Starting dynamic subtest: pipe-A-eDP-1
[936.707412] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.062s)
[937.009280] Starting dynamic subtest: pipe-B-eDP-1
[937.783749] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.784s)
[938.084739] Subtest plane-use-after-nonblocking-unbind: SUCCESS (1.437s)
[938.961238] [121/131] ( 85s left) i915_hangman (engine-error-state-capture)
[939.034439] Starting subtest: engine-error-state-capture
[939.036437] Starting dynamic subtest: rcs0
[942.650284] Dynamic subtest rcs0: SUCCESS (3.617s)
[942.664716] Starting dynamic subtest: bcs0
[948.667253] Dynamic subtest bcs0: SUCCESS (6.015s)
[948.681327] Starting dynamic subtest: vcs0
[953.656746] Dynamic subtest vcs0: SUCCESS (4.988s)
[953.670618] Starting dynamic subtest: vcs1
[959.609603] Dynamic subtest vcs1: SUCCESS (5.952s)
[959.622737] Starting dynamic subtest: vecs0
[964.601694] Dynamic subtest vecs0: SUCCESS (4.991s)
[964.616284] Starting dynamic subtest: ccs0
[969.656334] Dynamic subtest ccs0: SUCCESS (5.054s)
[969.658907] Subtest engine-error-state-capture: SUCCESS (30.634s)
[969.766957] [122/131] ( 54s left) gem_exec_schedule (pi-shared-iova)
[969.961500] Starting subtest: pi-shared-iova
[969.963697] Starting dynamic subtest: rcs0
[970.104910] Dynamic subtest rcs0: SUCCESS (0.143s)
[970.118591] Starting dynamic subtest: vcs0
[970.196468] Dynamic subtest vcs0: SUCCESS (0.092s)
[970.209620] Starting dynamic subtest: vcs1
[970.283379] Dynamic subtest vcs1: SUCCESS (0.086s)
[970.296541] Starting dynamic subtest: vecs0
[970.362098] Dynamic subtest vecs0: SUCCESS (0.078s)
[970.376430] Starting dynamic subtest: ccs0
[970.476578] Dynamic subtest ccs0: SUCCESS (0.114s)
[970.479056] Subtest pi-shared-iova: SUCCESS (0.517s)
[971.812432] [123/131] ( 52s left) syncobj_wait (invalid-reset-illegal-handle)
[971.878606] Starting subtest: invalid-reset-illegal-handle
[971.880826] Subtest invalid-reset-illegal-handle: SUCCESS (0.000s)
[972.020635] [124/131] ( 52s left) syncobj_wait (single-wait-all-for-submit-signaled)
[972.086849] Starting subtest: single-wait-all-for-submit-signaled
[972.088698] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[972.206213] [125/131] ( 52s left) kms_cursor_crc (cursor-sliding-512x170)
[972.505860] Starting subtest: cursor-sliding-512x170
[972.510183] Subtest cursor-sliding-512x170: SKIP (0.001s)
[972.688457] [126/131] ( 51s left) kms_chamelium_hpd (hdmi-hpd-fast)
[973.098734] Subtest hdmi-hpd-fast: SKIP (0.000s)
[973.939275] [127/131] ( 50s left) gem_ctx_persistence (hostile)
[973.999936] Starting subtest: hostile
[984.152592] Subtest hostile: SUCCESS (10.151s)
[984.321031] [128/131] ( 40s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt)
[984.432218] Starting subtest: fbc-rgb565-draw-blt
[988.655277] Subtest fbc-rgb565-draw-blt: SUCCESS (4.227s)
[989.882050] [129/131] ( 34s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite)
[989.976215] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite
[999.258117] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (9.279s)
[999.429264] [130/131] ( 25s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[999.537927] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[999.542116] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[999.674759] [131/131] ( 24s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-move)
[999.771467] Starting subtest: fbc-2p-scndscrn-spr-indfb-move
[999.775632] Subtest fbc-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[999.911808] 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.