[64.632973] Initializing watchdogs
[64.633051]   /dev/watchdog0
[64.647287] [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]
[64.651982] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[64.654298] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[64.658193] [001/132] (960s left) gem_ppgtt (shrink-vs-evict-any)
[64.734073] Starting subtest: shrink-vs-evict-any
[69.933823] Subtest shrink-vs-evict-any: SUCCESS (5.197s)
[70.009236] [002/132] (955s left) kms_frontbuffer_tracking (psr-rgb565-draw-render)
[70.104884] Starting subtest: psr-rgb565-draw-render
[77.183915] Subtest psr-rgb565-draw-render: SUCCESS (7.084s)
[77.320369] [003/132] (947s left) gem_busy (close-race)
[77.388949] Starting subtest: close-race
[100.949198] Subtest close-race: SUCCESS (23.558s)
[101.299997] [FACT gem_busy (close-race)] new: kernel.kmod_is_loaded.vgem: true
[101.303787] [004/132] (923s left) gem_mmap_offset (mmap-boundaries)
[101.401043] Starting subtest: mmap-boundaries
[101.403511] Starting dynamic subtest: smem0
[101.681972] Dynamic subtest smem0: SUCCESS (0.283s)
[101.684824] Subtest mmap-boundaries: SUCCESS (0.283s)
[101.805560] [005/132] (923s left) gem_sync (basic-each)
[101.878097] Starting subtest: basic-each
[104.393121] Subtest basic-each: SUCCESS (2.526s)
[104.578125] [006/132] (920s left) kms_content_protection (dp-mst-type-1)
[104.735429] Starting subtest: dp-mst-type-1
[104.739983] Subtest dp-mst-type-1: SKIP (0.000s)
[104.847012] [007/132] (920s left) kms_invalid_mode (bad-vtotal)
[104.947577] Starting subtest: bad-vtotal
[104.950017] Starting dynamic subtest: pipe-A-eDP-1
[105.241950] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.295s)
[105.251717] Starting dynamic subtest: pipe-B-eDP-1
[105.253892] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[105.261255] Starting dynamic subtest: pipe-C-eDP-1
[105.263401] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[105.271019] Starting dynamic subtest: pipe-D-eDP-1
[105.272959] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[105.274880] Subtest bad-vtotal: SUCCESS (0.303s)
[106.094259] [008/132] (919s left) gem_exec_balancer (persistence)
[106.363327] Starting subtest: persistence
[107.276038] Subtest persistence: SUCCESS (0.911s)
[107.386701] [009/132] (918s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-upscaling)
[107.480227] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-upscaling
[107.484702] Subtest flip-64bpp-ytile-to-16bpp-ytile-upscaling: SKIP (0.000s)
[107.550790] [010/132] (917s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-5)
[107.645956] Starting subtest: planes-unity-scaling-downscale-factor-0-5
[107.648364] Starting dynamic subtest: pipe-A
[107.666655] Dynamic subtest pipe-A: SKIP (0.018s)
[107.677384] Starting dynamic subtest: pipe-B
[107.704486] Dynamic subtest pipe-B: SKIP (0.037s)
[107.712968] Starting dynamic subtest: pipe-C
[107.740333] Dynamic subtest pipe-C: SKIP (0.036s)
[107.748280] Starting dynamic subtest: pipe-D
[107.777932] Dynamic subtest pipe-D: SKIP (0.036s)
[107.781925] Subtest planes-unity-scaling-downscale-factor-0-5: SKIP (0.128s)
[107.872282] [011/132] (917s left) kms_frontbuffer_tracking (psr-rgb565-draw-blt)
[107.977804] Starting subtest: psr-rgb565-draw-blt
[115.134903] Subtest psr-rgb565-draw-blt: SUCCESS (7.160s)
[115.270176] [012/132] (910s left) kms_psr (fbc-psr2-sprite-plane-move)
[115.363965] Starting subtest: fbc-psr2-sprite-plane-move
[115.366275] Starting dynamic subtest: eDP-1
[115.370575] Dynamic subtest eDP-1: SKIP (0.000s)
[115.374855] Subtest fbc-psr2-sprite-plane-move: SKIP (0.000s)
[115.432244] [013/132] (910s left) kms_big_fb (yf-tiled-addfb-size-overflow)
[115.548235] Starting subtest: yf-tiled-addfb-size-overflow
[115.552302] Subtest yf-tiled-addfb-size-overflow: SKIP (0.000s)
[115.659016] [014/132] (910s left) kms_psr (pr-sprite-plane-onoff)
[115.745833] Starting subtest: pr-sprite-plane-onoff
[115.750137] Subtest pr-sprite-plane-onoff: SKIP (0.000s)
[115.816227] [015/132] (909s left) kms_cursor_legacy (flip-vs-cursor-crc-legacy)
[115.927963] Starting subtest: flip-vs-cursor-crc-legacy
[116.620586] Subtest flip-vs-cursor-crc-legacy: SUCCESS (0.692s)
[117.455834] [016/132] (908s left) kms_cdclk (plane-scaling)
[117.551803] Starting subtest: plane-scaling
[117.554167] Starting dynamic subtest: pipe-A-eDP-1
[117.842471] Dynamic subtest pipe-A-eDP-1: SKIP (0.289s)
[117.852565] Starting dynamic subtest: pipe-B-eDP-1
[117.857261] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[117.865371] Starting dynamic subtest: pipe-C-eDP-1
[117.869927] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[117.877983] Starting dynamic subtest: pipe-D-eDP-1
[117.882553] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[117.886927] Subtest plane-scaling: SKIP (0.295s)
[118.684890] [017/132] (907s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[118.784472] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[118.788618] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[118.882183] [018/132] (906s left) gem_mmap_gtt (basic-wc)
[118.938789] Subtest basic-wc: SKIP (0.000s)
[118.998611] [019/132] (906s left) gem_ctx_isolation (preservation-s3)
[119.061026] Starting subtest: preservation-S3
[119.063310] Starting dynamic subtest: rcs0
[135.344595] Dynamic subtest rcs0: SUCCESS (2.532s)
[135.353768] Starting dynamic subtest: bcs0
[152.360584] Dynamic subtest bcs0: SUCCESS (1.683s)
[152.372078] Starting dynamic subtest: vcs0
[169.346931] Dynamic subtest vcs0: SUCCESS (3.084s)
[169.356818] Starting dynamic subtest: vcs1
[187.349535] Dynamic subtest vcs1: SUCCESS (5.047s)
[187.358593] Starting dynamic subtest: vecs0
[205.353163] Dynamic subtest vecs0: SUCCESS (4.927s)
[205.364001] Starting dynamic subtest: ccs0
[223.340573] Dynamic subtest ccs0: SUCCESS (3.168s)
[223.343389] Subtest preservation-S3: SUCCESS (20.445s)
[230.582464] [020/132] (802s left) sw_sync (sync_random_merge)
[230.648531] Starting subtest: sync_random_merge
[230.695262] Subtest sync_random_merge: SUCCESS (0.048s)
[230.759089] [021/132] (802s left) kms_psr (psr-primary-mmap-cpu)
[230.869004] Starting subtest: psr-primary-mmap-cpu
[230.871331] Starting dynamic subtest: eDP-1
[232.668212] Dynamic subtest eDP-1: SUCCESS (1.798s)
[232.670469] Subtest psr-primary-mmap-cpu: SUCCESS (1.799s)
[233.541329] [022/132] (799s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-sf)
[233.632042] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-sf
[233.635932] Subtest fbc-pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.000s)
[233.701979] [023/132] (799s left) gem_lmem_swapping (basic)
[233.747418] Subtest basic: SKIP (0.000s)
[233.802204] [024/132] (799s left) gem_render_copy (y-tiled-ccs-to-y-tiled-mc-ccs)
[233.854552] Starting subtest: y-tiled-ccs-to-y-tiled-mc-ccs
[233.859286] Subtest y-tiled-ccs-to-y-tiled-mc-ccs: SKIP (0.000s)
[234.041152] [025/132] (799s left) kms_pm_rpm (fences)
[235.881602] Starting subtest: fences
[235.886464] Subtest fences: SKIP (0.000s)
[236.300039] [026/132] (796s left) kms_ccs (crc-sprite-planes-basic-y-tiled-ccs)
[236.396388] Starting subtest: crc-sprite-planes-basic-y-tiled-ccs
[236.398803] Starting dynamic subtest: pipe-A-eDP-1
[236.459581] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[236.469995] Starting dynamic subtest: pipe-B-eDP-1
[236.525517] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[236.537588] Starting dynamic subtest: pipe-C-eDP-1
[236.594179] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[236.604126] Starting dynamic subtest: pipe-D-eDP-1
[236.660584] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[236.664588] Subtest crc-sprite-planes-basic-y-tiled-ccs: SKIP (0.008s)
[238.579335] [027/132] (794s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions-varying-size)
[238.707302] Starting subtest: short-flip-after-cursor-atomic-transitions-varying-size
[239.669172] Subtest short-flip-after-cursor-atomic-transitions-varying-size: SUCCESS (0.961s)
[240.498418] [028/132] (792s left) kms_big_fb (y-tiled-16bpp-rotate-90)
[240.599503] Starting subtest: y-tiled-16bpp-rotate-90
[240.604077] Subtest y-tiled-16bpp-rotate-90: SKIP (0.000s)
[240.705537] [029/132] (792s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-wc)
[240.805384] Starting subtest: fbcpsr-rgb565-draw-mmap-wc
[247.576167] Subtest fbcpsr-rgb565-draw-mmap-wc: SUCCESS (6.772s)
[247.691888] [030/132] (785s left) gem_exec_fence (syncobj-repeat)
[248.144136] Starting subtest: syncobj-repeat
[248.332496] Subtest syncobj-repeat: SUCCESS (0.186s)
[249.817323] [031/132] (783s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[249.904773] Starting subtest: nonblocking-crc-frame-sequence
[249.907138] Starting dynamic subtest: pipe-A-eDP-1
[250.672019] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.768s)
[250.687351] Starting dynamic subtest: pipe-B-eDP-1
[252.174203] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.498s)
[252.193137] Starting dynamic subtest: pipe-C-eDP-1
[253.524432] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.347s)
[253.534788] Starting dynamic subtest: pipe-D-eDP-1
[254.859053] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.334s)
[254.861306] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.954s)
[255.660371] [032/132] (777s left) api_intel_allocator (fork-simple-once)
[255.714996] Starting subtest: fork-simple-once
[255.728526] Subtest fork-simple-once: SUCCESS (0.016s)
[255.852171] [033/132] (777s left) gem_render_copy (mixed-tiled-to-yf-tiled-ccs)
[255.903361] Starting subtest: mixed-tiled-to-yf-tiled-ccs
[255.907752] Subtest mixed-tiled-to-yf-tiled-ccs: SKIP (0.000s)
[256.090174] [034/132] (777s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc)
[256.194879] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc
[256.197213] Starting dynamic subtest: pipe-A-eDP-1
[256.220341] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[256.233185] Starting dynamic subtest: pipe-B-eDP-1
[256.253804] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[256.261905] Starting dynamic subtest: pipe-C-eDP-1
[256.282295] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[256.290304] Starting dynamic subtest: pipe-D-eDP-1
[256.310881] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[256.314946] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc: SKIP (0.005s)
[258.445414] [035/132] (775s left) kms_big_fb (4-tiled-64bpp-rotate-0)
[258.548966] Starting subtest: 4-tiled-64bpp-rotate-0
[260.128342] Subtest 4-tiled-64bpp-rotate-0: SUCCESS (1.579s)
[260.285198] [036/132] (773s left) gem_create (create-clear)
[260.342139] Starting subtest: create-clear
[260.344462] Starting dynamic subtest: smem0
[293.082250] Dynamic subtest smem0: SUCCESS (32.732s)
[293.084634] Subtest create-clear: SUCCESS (32.734s)
[293.272839] [037/132] (740s left) i915_query (query-regions-sanity-check)
[293.337849] Starting subtest: query-regions-sanity-check
[293.347753] Subtest query-regions-sanity-check: SUCCESS (0.013s)
[293.478533] [038/132] (740s left) gem_exec_reloc (basic-wc-gtt)
[293.536660] Subtest basic-wc-gtt: SKIP (0.000s)
[293.604182] [039/132] (740s left) kms_color_pipeline (plane-lut1d-post-ctm3x4)
[293.698636] Starting subtest: plane-lut1d-post-ctm3x4
[293.867184] Starting dynamic subtest: pipe-A-plane-0
[294.225947] Dynamic subtest pipe-A-plane-0: FAIL (0.194s)
[294.239184] Starting dynamic subtest: pipe-A-plane-1
[294.416108] Dynamic subtest pipe-A-plane-1: FAIL (0.170s)
[294.426027] Starting dynamic subtest: pipe-A-plane-2
[294.629648] Dynamic subtest pipe-A-plane-2: FAIL (0.187s)
[295.681456] Starting dynamic subtest: pipe-B-plane-0
[296.114431] Dynamic subtest pipe-B-plane-0: FAIL (0.164s)
[296.124899] Starting dynamic subtest: pipe-B-plane-1
[296.437131] Dynamic subtest pipe-B-plane-1: FAIL (0.166s)
[296.447619] Starting dynamic subtest: pipe-B-plane-2
[296.740689] Dynamic subtest pipe-B-plane-2: FAIL (0.177s)
[297.400030] Starting dynamic subtest: pipe-C-plane-0
[297.894623] Dynamic subtest pipe-C-plane-0: FAIL (0.129s)
[297.905129] Starting dynamic subtest: pipe-C-plane-1
[298.298378] Dynamic subtest pipe-C-plane-1: FAIL (0.611s)
[298.312021] Starting dynamic subtest: pipe-C-plane-2
[298.668964] Dynamic subtest pipe-C-plane-2: FAIL (0.173s)
[299.517798] Starting dynamic subtest: pipe-D-plane-0
[300.117544] Dynamic subtest pipe-D-plane-0: FAIL (0.152s)
[300.128023] Starting dynamic subtest: pipe-D-plane-1
[300.588942] Dynamic subtest pipe-D-plane-1: FAIL (0.616s)
[300.599452] Starting dynamic subtest: pipe-D-plane-2
[301.040687] Dynamic subtest pipe-D-plane-2: FAIL (0.575s)
[301.151433] Subtest plane-lut1d-post-ctm3x4: FAIL (7.452s)
[301.986180] [040/132] (731s left) gem_mmap_gtt (cpuset-basic-small-copy)
[302.052759] Subtest cpuset-basic-small-copy: SKIP (0.000s)
[302.124667] [041/132] (731s left) kms_prime (basic-crc-hybrid)
[302.214370] Subtest basic-crc-hybrid: SKIP (0.000s)
[302.318675] [042/132] (731s left) gen9_exec_parse (basic-rejected-ctx-param)
[302.377497] Subtest basic-rejected-ctx-param: SKIP (0.000s)
[302.453091] [043/132] (731s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier)
[302.541824] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier
[302.545781] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier: SKIP (0.000s)
[302.614520] [044/132] (731s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-pwrite)
[302.717023] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-pwrite
[302.720969] Subtest fbc-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[302.819750] [045/132] (731s left) kms_psr (psr2-sprite-blt)
[302.920172] Starting subtest: psr2-sprite-blt
[302.922099] Starting dynamic subtest: eDP-1
[304.685663] Dynamic subtest eDP-1: SUCCESS (1.764s)
[304.687923] Subtest psr2-sprite-blt: SUCCESS (1.765s)
[305.548714] [046/132] (728s left) sysfs_timeslice_duration (timeout)
[305.620565] Starting subtest: timeout
[305.622954] Starting dynamic subtest: vecs0
[314.765049] Dynamic subtest vecs0: SUCCESS (9.139s)
[314.776583] Starting dynamic subtest: ccs0
[316.074889] Dynamic subtest ccs0: SUCCESS (1.310s)
[316.084795] Starting dynamic subtest: vcs0
[325.776817] Dynamic subtest vcs0: SUCCESS (9.697s)
[325.789704] Starting dynamic subtest: bcs0
[326.412506] Dynamic subtest bcs0: SUCCESS (0.637s)
[326.422190] Starting dynamic subtest: rcs0
[327.702850] Dynamic subtest rcs0: SUCCESS (1.286s)
[327.713086] Starting dynamic subtest: vcs1
[337.742223] Dynamic subtest vcs1: SUCCESS (10.037s)
[337.744873] Subtest timeout: SUCCESS (32.117s)
[337.818043] [047/132] (696s left) core_hotunplug (unbind-rebind)
[337.943876] Starting subtest: unbind-rebind
[343.898052] Subtest unbind-rebind: SUCCESS (5.956s)
[343.938419] [048/132] (690s left) gem_render_copy (x-tiled)
[345.662124] Starting subtest: x-tiled
[345.664516] Starting dynamic subtest: smem
[345.706536] Dynamic subtest smem: SUCCESS (0.047s)
[345.709588] Subtest x-tiled: SUCCESS (0.048s)
[345.895738] [049/132] (688s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-msflip-blt)
[346.002421] Starting subtest: psr-2p-scndscrn-indfb-msflip-blt
[346.006523] Subtest psr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[346.089072] [050/132] (688s left) kms_ccs (bad-pixel-format-yf-tiled-ccs)
[346.186088] Starting subtest: bad-pixel-format-yf-tiled-ccs
[346.188441] Starting dynamic subtest: pipe-A-eDP-1
[346.211003] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[346.225335] Starting dynamic subtest: pipe-B-eDP-1
[346.246891] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[346.255624] Starting dynamic subtest: pipe-C-eDP-1
[346.276976] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[346.285674] Starting dynamic subtest: pipe-D-eDP-1
[346.308191] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[346.312475] Subtest bad-pixel-format-yf-tiled-ccs: SKIP (0.004s)
[348.437031] [051/132] (685s left) kms_hdr (bpc-switch-suspend)
[348.526932] Starting subtest: bpc-switch-suspend
[349.618468] Starting dynamic subtest: pipe-A-eDP-1
[369.455692] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.005s)
[369.458010] Subtest bpc-switch-suspend: SUCCESS (6.096s)
[374.570896] [052/132] (664s left) gen9_exec_parse (valid-registers)
[374.643572] Subtest valid-registers: SKIP (0.000s)
[374.706226] [053/132] (664s left) kms_chamelium_frames (hdmi-aspect-ratio)
[375.098700] Subtest hdmi-aspect-ratio: SKIP (0.000s)
[375.931552] [054/132] (663s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-32bpp-yftile-downscaling)
[376.027291] Starting subtest: flip-64bpp-yftile-to-32bpp-yftile-downscaling
[376.031861] Subtest flip-64bpp-yftile-to-32bpp-yftile-downscaling: SKIP (0.000s)
[376.101518] [055/132] (663s left) syncobj_timeline (single-wait-signaled)
[376.171376] Starting subtest: single-wait-signaled
[376.173602] Subtest single-wait-signaled: SUCCESS (0.000s)
[376.288426] [056/132] (663s left) kms_properties (get_properties-sanity-non-atomic)
[376.378211] Starting subtest: get_properties-sanity-non-atomic
[376.380565] Subtest get_properties-sanity-non-atomic: SUCCESS (0.002s)
[376.506116] [057/132] (663s left) gem_bad_reloc (negative-reloc-lut)
[376.574479] Subtest negative-reloc-lut: SKIP (0.000s)
[376.650568] [058/132] (662s left) kms_frontbuffer_tracking (fbcpsr-tiling-4)
[376.752399] Starting subtest: fbcpsr-tiling-4
[383.914807] Subtest fbcpsr-tiling-4: SUCCESS (7.158s)
[384.059102] [059/132] (655s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions)
[384.176084] Starting subtest: cursorB-vs-flipA-atomic-transitions
[384.180618] Subtest cursorB-vs-flipA-atomic-transitions: SKIP (0.001s)
[384.324188] [060/132] (655s left) gem_userptr_blits (input-checking)
[384.386362] Starting subtest: input-checking
[384.390042] Subtest input-checking: SUCCESS (0.000s)
[384.508584] [061/132] (655s left) kms_lease (lease-invalid-plane)
[384.608770] Starting subtest: lease-invalid-plane
[384.611090] Starting dynamic subtest: pipe-A-eDP-1
[384.613254] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[384.622815] Starting dynamic subtest: pipe-B-eDP-1
[384.624922] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[384.633945] Starting dynamic subtest: pipe-C-eDP-1
[384.635819] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[384.644813] Starting dynamic subtest: pipe-D-eDP-1
[384.646943] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[384.649094] Subtest lease-invalid-plane: SUCCESS (0.005s)
[384.686874] [062/132] (655s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-fullscreen)
[384.788179] Starting subtest: psr-2p-primscrn-spr-indfb-fullscreen
[384.792413] Subtest psr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[384.894216] [063/132] (654s left) gem_exec_schedule (noreorder)
[385.073615] Starting subtest: noreorder
[385.076327] Starting dynamic subtest: rcs0
[385.103489] Dynamic subtest rcs0: SUCCESS (0.030s)
[385.119054] Starting dynamic subtest: vcs0
[386.569797] Dynamic subtest vcs0: SUCCESS (1.461s)
[386.582191] Starting dynamic subtest: vcs1
[388.613215] Dynamic subtest vcs1: SUCCESS (2.039s)
[388.626122] Starting dynamic subtest: vecs0
[390.599096] Dynamic subtest vecs0: SUCCESS (1.983s)
[390.611030] Starting dynamic subtest: ccs0
[390.664346] Dynamic subtest ccs0: SUCCESS (0.064s)
[390.666844] Subtest noreorder: SUCCESS (5.589s)
[390.854181] [064/132] (649s left) kms_async_flips (async-flip-with-page-flip-events-linear-atomic)
[390.956838] Starting subtest: async-flip-with-page-flip-events-linear-atomic
[391.329979] Starting dynamic subtest: pipe-A-eDP-1
[394.075030] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.743s)
[394.461842] Starting dynamic subtest: pipe-B-eDP-1
[397.207997] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.752s)
[397.569502] Starting dynamic subtest: pipe-C-eDP-1
[400.332863] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.768s)
[400.696554] Starting dynamic subtest: pipe-D-eDP-1
[403.458460] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.769s)
[403.461615] Subtest async-flip-with-page-flip-events-linear-atomic: SUCCESS (12.498s)
[404.645262] [065/132] (635s left) gem_userptr_blits (set-cache-level)
[404.709558] Starting subtest: set-cache-level
[404.714532] Subtest set-cache-level: SKIP (0.000s)
[404.840961] [066/132] (635s left) kms_flip (blocking-absolute-wf_vblank)
[404.940183] Starting subtest: blocking-absolute-wf_vblank
[404.942532] Starting dynamic subtest: A-eDP1
[413.713972] Dynamic subtest A-eDP1: SUCCESS (8.770s)
[413.727856] Starting dynamic subtest: B-eDP1
[422.441336] Dynamic subtest B-eDP1: SUCCESS (8.722s)
[422.461109] Starting dynamic subtest: C-eDP1
[431.161236] Dynamic subtest C-eDP1: SUCCESS (8.715s)
[431.174112] Starting dynamic subtest: D-eDP1
[439.894289] Dynamic subtest D-eDP1: SUCCESS (8.728s)
[439.900212] Subtest blocking-absolute-wf_vblank: SUCCESS (34.952s)
[441.113593] [067/132] (598s left) kms_feature_discovery (chamelium)
[441.209120] Starting subtest: chamelium
[441.213175] Subtest chamelium: SKIP (0.000s)
[441.282094] [068/132] (598s left) gem_ctx_param (invalid-size-get)
[441.342590] Starting subtest: invalid-size-get
[441.344975] Subtest invalid-size-get: SUCCESS (0.001s)
[441.441275] [069/132] (598s left) gem_exec_schedule (u-independent)
[441.518235] Starting subtest: u-independent
[441.520198] Starting dynamic subtest: rcs0
[443.591200] Dynamic subtest rcs0: SUCCESS (2.073s)
[443.604230] Starting dynamic subtest: vcs0
[445.584477] Dynamic subtest vcs0: SUCCESS (1.991s)
[445.597142] Starting dynamic subtest: vcs1
[447.567116] Dynamic subtest vcs1: SUCCESS (1.980s)
[447.579667] Starting dynamic subtest: vecs0
[449.631363] Dynamic subtest vecs0: SUCCESS (2.061s)
[449.643029] Starting dynamic subtest: ccs0
[451.590142] Dynamic subtest ccs0: SUCCESS (1.956s)
[451.592821] Subtest u-independent: SUCCESS (10.072s)
[451.896527] [070/132] (588s left) i915_pm_rpm (gem-mmap-type)
[453.729075] Starting subtest: gem-mmap-type
[453.731793] Starting dynamic subtest: wb-smem0
[457.221732] Dynamic subtest wb-smem0: SUCCESS (3.494s)
[457.231792] Starting dynamic subtest: wc-smem0
[460.379239] Dynamic subtest wc-smem0: SUCCESS (3.155s)
[460.389693] Starting dynamic subtest: uc-smem0
[463.565824] Dynamic subtest uc-smem0: SUCCESS (3.183s)
[463.568172] Subtest gem-mmap-type: SUCCESS (9.837s)
[463.657052] [071/132] (576s left) syncobj_timeline (multi-wait-for-submit-available-submitted-signaled)
[463.718367] Starting subtest: multi-wait-for-submit-available-submitted-signaled
[463.720628] Subtest multi-wait-for-submit-available-submitted-signaled: SUCCESS (0.000s)
[463.821679] [072/132] (576s left) kms_cursor_crc (cursor-onscreen-128x42)
[464.047213] Starting subtest: cursor-onscreen-128x42
[464.097275] Subtest cursor-onscreen-128x42: SKIP (0.043s)
[464.310535] [073/132] (575s left) sriov_basic (enable-vfs-bind-unbind-each)
[464.370674] Starting subtest: enable-vfs-bind-unbind-each
[464.372882] Starting dynamic subtest: numvfs-1
[464.443912] Dynamic subtest numvfs-1: FAIL (0.005s)
[464.458485] Starting dynamic subtest: numvfs-2
[464.530047] Dynamic subtest numvfs-2: FAIL (0.004s)
[464.541192] Starting dynamic subtest: numvfs-3
[464.602890] Dynamic subtest numvfs-3: FAIL (0.003s)
[464.613047] Starting dynamic subtest: numvfs-4
[464.672540] Dynamic subtest numvfs-4: FAIL (0.004s)
[464.683510] Starting dynamic subtest: numvfs-5
[464.744609] Dynamic subtest numvfs-5: FAIL (0.004s)
[464.755226] Starting dynamic subtest: numvfs-6
[464.815276] Dynamic subtest numvfs-6: FAIL (0.004s)
[464.825709] Starting dynamic subtest: numvfs-7
[464.885873] Dynamic subtest numvfs-7: FAIL (0.004s)
[464.895726] Starting dynamic subtest: numvfs-random
[464.956522] Dynamic subtest numvfs-random: FAIL (0.004s)
[464.958504] Subtest enable-vfs-bind-unbind-each: FAIL (0.036s)
[464.997625] [074/132] (575s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-180-hflip)
[465.111904] Starting subtest: linear-max-hw-stride-64bpp-rotate-180-hflip
[465.115334] Subtest linear-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[465.204400] [075/132] (575s left) kms_flip (2x-flip-vs-modeset-vs-hang)
[465.300022] Starting subtest: 2x-flip-vs-modeset-vs-hang
[465.311329] Subtest 2x-flip-vs-modeset-vs-hang: SKIP (0.013s)
[465.388635] [076/132] (574s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier)
[465.478141] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier
[465.481443] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier: SKIP (0.000s)
[465.549218] [077/132] (574s left) gem_exec_whisper (basic-fds-forked)
[465.612462] Starting subtest: basic-fds-forked
[489.749794] Subtest basic-fds-forked: SUCCESS (24.141s)
[489.938330] [078/132] (550s left) perf (invalid-create-userspace-config)
[490.072790] Starting subtest: invalid-create-userspace-config
[490.074941] Subtest invalid-create-userspace-config: SUCCESS (0.001s)
[490.114415] [079/132] (550s left) kms_vblank (wait-idle)
[490.201897] Starting subtest: wait-idle
[490.204089] Starting dynamic subtest: pipe-A-eDP-1
[491.958306] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.756s)
[491.973055] Starting dynamic subtest: pipe-D-eDP-1
[494.444024] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.483s)
[494.446291] Subtest wait-idle: SUCCESS (4.242s)
[495.256690] [080/132] (545s left) syncobj_wait (reset-multiple-signaled)
[495.318938] Starting subtest: reset-multiple-signaled
[495.321218] Subtest reset-multiple-signaled: SUCCESS (0.000s)
[495.420915] [081/132] (545s left) kms_atomic_interruptible (legacy-dpms)
[495.506134] Starting subtest: legacy-dpms
[495.508399] Starting dynamic subtest: pipe-A-eDP-1
[502.913498] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.407s)
[502.915724] Subtest legacy-dpms: SUCCESS (7.408s)
[503.730585] [082/132] (536s left) i915_hangman (gt-engine-hang)
[503.809430] Starting subtest: gt-engine-hang
[503.811667] Starting dynamic subtest: rcs0
[505.509445] Dynamic subtest rcs0: SUCCESS (1.699s)
[505.522597] Starting dynamic subtest: bcs0
[507.493512] Dynamic subtest bcs0: SUCCESS (1.981s)
[507.504688] Starting dynamic subtest: vcs0
[508.516612] Dynamic subtest vcs0: SUCCESS (1.021s)
[508.528421] Starting dynamic subtest: vcs1
[510.563677] Dynamic subtest vcs1: SUCCESS (2.044s)
[510.573695] Starting dynamic subtest: vecs0
[512.548338] Dynamic subtest vecs0: SUCCESS (1.982s)
[512.560730] Starting dynamic subtest: ccs0
[514.532852] Dynamic subtest ccs0: SUCCESS (1.982s)
[514.535571] Subtest gt-engine-hang: SUCCESS (10.725s)
[514.677151] [083/132] (525s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[514.773957] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[514.778369] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[514.884876] [084/132] (525s left) syncobj_timeline (invalid-transfer-bad-pad)
[514.932492] Starting subtest: invalid-transfer-bad-pad
[514.934802] Subtest invalid-transfer-bad-pad: SUCCESS (0.000s)
[515.030718] [085/132] (525s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-pgflip-blt)
[515.118985] Starting subtest: psr-1p-primscrn-indfb-pgflip-blt
[522.512867] Subtest psr-1p-primscrn-indfb-pgflip-blt: SUCCESS (7.397s)
[522.663385] [086/132] (518s left) kms_big_fb (linear-32bpp-rotate-0)
[522.767212] Starting subtest: linear-32bpp-rotate-0
[524.302117] Subtest linear-32bpp-rotate-0: SUCCESS (1.536s)
[524.457838] [087/132] (516s left) kms_cursor_legacy (cursorb-vs-flipa-atomic)
[524.579809] Starting subtest: cursorB-vs-flipA-atomic
[524.583590] Subtest cursorB-vs-flipA-atomic: SKIP (0.001s)
[524.722028] [088/132] (516s left) gem_ctx_bad_destroy (invalid-pad)
[524.782080] Starting subtest: invalid-pad
[524.783921] Subtest invalid-pad: SUCCESS (0.000s)
[524.853184] [089/132] (516s left) gem_softpin (noreloc-interruptible)
[524.919955] Starting subtest: noreloc-interruptible
[530.341293] Subtest noreloc-interruptible: SUCCESS (5.418s)
[530.461364] [090/132] (510s left) kms_setmode (invalid-clone-exclusive-crtc)
[530.519747] Starting subtest: invalid-clone-exclusive-crtc
[530.526692] Subtest invalid-clone-exclusive-crtc: SKIP (0.000s)
[530.644984] [091/132] (510s left) kms_flip (2x-busy-flip)
[530.748443] Starting subtest: 2x-busy-flip
[530.758614] Subtest 2x-busy-flip: SKIP (0.011s)
[530.847263] [092/132] (510s left) kms_atomic_transition (plane-use-after-nonblocking-unbind)
[530.943664] Starting subtest: plane-use-after-nonblocking-unbind
[530.946001] Starting dynamic subtest: pipe-A-eDP-1
[531.008874] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.066s)
[531.308390] Starting dynamic subtest: pipe-B-eDP-1
[532.063813] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.766s)
[532.364290] Subtest plane-use-after-nonblocking-unbind: SUCCESS (1.422s)
[533.222662] [093/132] (507s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions-varying-size)
[533.347820] Starting subtest: cursorA-vs-flipB-atomic-transitions-varying-size
[533.352570] Subtest cursorA-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[533.496292] [094/132] (507s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-gtt)
[533.585219] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-gtt
[533.589006] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[533.680067] [095/132] (507s left) syncobj_timeline (etime-multi-wait-for-submit-unsubmitted)
[533.736118] Starting subtest: etime-multi-wait-for-submit-unsubmitted
[533.842132] Subtest etime-multi-wait-for-submit-unsubmitted: SUCCESS (0.105s)
[533.923960] [096/132] (507s left) kms_ccs (crc-primary-basic-4-tiled-mtl-mc-ccs)
[534.024078] Starting subtest: crc-primary-basic-4-tiled-mtl-mc-ccs
[534.026382] Starting dynamic subtest: pipe-A-eDP-1
[536.262391] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.240s)
[536.272244] Starting dynamic subtest: pipe-B-eDP-1
[538.728920] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.464s)
[538.738117] Starting dynamic subtest: pipe-C-eDP-1
[540.898142] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.168s)
[540.908746] Starting dynamic subtest: pipe-D-eDP-1
[542.973510] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.074s)
[542.975616] Subtest crc-primary-basic-4-tiled-mtl-mc-ccs: SUCCESS (8.952s)
[544.156392] [097/132] (497s left) kms_cursor_crc (cursor-sliding-32x10)
[544.319507] Starting subtest: cursor-sliding-32x10
[544.370365] Subtest cursor-sliding-32x10: SKIP (0.044s)
[544.659595] [098/132] (496s left) kms_chamelium_hpd (vga-hpd-with-enabled-mode)
[545.067472] Subtest vga-hpd-with-enabled-mode: SKIP (0.000s)
[545.864298] [099/132] (495s left) kms_flip (flip-vs-suspend)
[545.959814] Starting subtest: flip-vs-suspend
[545.961956] Starting dynamic subtest: A-eDP1
[579.330253] Dynamic subtest A-eDP1: SUCCESS (4.279s)
[579.347183] Starting dynamic subtest: B-eDP1
[613.329859] Dynamic subtest B-eDP1: SUCCESS (4.141s)
[613.340771] Starting dynamic subtest: C-eDP1
[647.333124] Dynamic subtest C-eDP1: SUCCESS (4.145s)
[647.347653] Starting dynamic subtest: D-eDP1
[681.343851] Dynamic subtest D-eDP1: SUCCESS (4.197s)
[681.346182] Subtest flip-vs-suspend: SUCCESS (16.771s)
[691.560588] [100/132] (358s left) kms_psr (fbc-psr2-primary-page-flip)
[691.681657] Starting subtest: fbc-psr2-primary-page-flip
[691.684107] Starting dynamic subtest: eDP-1
[691.688572] Dynamic subtest eDP-1: SKIP (0.000s)
[691.693024] Subtest fbc-psr2-primary-page-flip: SKIP (0.001s)
[691.755740] [101/132] (358s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-render)
[691.856158] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-render
[698.694784] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-render: SUCCESS (6.835s)
[698.844713] [102/132] (351s left) gem_userptr_blits (sync-unmap)
[698.920880] Starting subtest: sync-unmap
[698.940284] Subtest sync-unmap: SUCCESS (0.021s)
[699.058974] [103/132] (351s left) kms_async_flips (invalid-async-flip)
[699.153605] Starting subtest: invalid-async-flip
[699.532962] Starting dynamic subtest: pipe-A-eDP-1
[700.309210] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.777s)
[700.680211] Starting dynamic subtest: pipe-B-eDP-1
[701.453981] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.783s)
[701.816917] Starting dynamic subtest: pipe-C-eDP-1
[702.584032] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.774s)
[702.945043] Starting dynamic subtest: pipe-D-eDP-1
[703.711063] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.776s)
[703.713405] Subtest invalid-async-flip: SUCCESS (4.557s)
[704.860160] [104/132] (345s left) gem_mmap_gtt (basic-short)
[704.921012] Subtest basic-short: SKIP (0.000s)
[704.987169] [105/132] (345s left) syncobj_basic (bad-handle-to-fd)
[705.043534] Starting subtest: bad-handle-to-fd
[705.045882] Subtest bad-handle-to-fd: SUCCESS (0.000s)
[705.151028] [106/132] (345s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-pwrite)
[705.250483] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-pwrite
[705.254593] Subtest fbc-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[705.355576] [107/132] (345s left) kms_universal_plane (disable-primary-vs-flip)
[705.455120] Starting subtest: disable-primary-vs-flip
[705.457351] Starting dynamic subtest: pipe-A-eDP-1
[707.060256] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.603s)
[707.070761] Starting dynamic subtest: pipe-B-eDP-1
[709.314154] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.251s)
[709.324421] Starting dynamic subtest: pipe-C-eDP-1
[711.506881] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.191s)
[711.518476] Starting dynamic subtest: pipe-D-eDP-1
[713.772854] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.264s)
[713.775036] Subtest disable-primary-vs-flip: SUCCESS (8.315s)
[714.570477] [108/132] (336s left) gem_ctx_persistence (legacy-engines-cleanup)
[714.633087] Starting subtest: legacy-engines-cleanup
[714.634942] Starting dynamic subtest: render
[714.704313] Dynamic subtest render: SUCCESS (0.073s)
[714.717045] Starting dynamic subtest: bsd1
[714.728485] Dynamic subtest bsd1: SUCCESS (0.022s)
[714.737998] Starting dynamic subtest: bsd2
[714.788283] Dynamic subtest bsd2: SUCCESS (0.058s)
[714.800874] Starting dynamic subtest: blt
[714.826312] Dynamic subtest blt: SUCCESS (0.036s)
[714.839279] Starting dynamic subtest: vebox
[714.870265] Dynamic subtest vebox: SUCCESS (0.041s)
[714.872571] Subtest legacy-engines-cleanup: SUCCESS (0.241s)
[714.950537] [109/132] (335s left) gem_exec_params (cliprects-invalid)
[715.006497] Starting subtest: cliprects-invalid
[715.009099] Subtest cliprects-invalid: SUCCESS (0.000s)
[715.103523] [110/132] (335s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[715.200437] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[715.204934] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s)
[715.317752] [111/132] (335s left) kms_addfb_basic (small-bo)
[715.422271] Starting subtest: small-bo
[715.424579] Subtest small-bo: SUCCESS (0.000s)
[715.513474] [112/132] (335s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[715.607704] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[715.611122] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[715.702235] [113/132] (335s left) gem_exec_reloc (basic-cpu-gtt)
[715.752877] Subtest basic-cpu-gtt: SKIP (0.000s)
[715.831783] [114/132] (335s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-blt)
[715.915731] Starting subtest: psr-2p-primscrn-cur-indfb-draw-blt
[715.919090] Subtest psr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[716.008787] [115/132] (335s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-exceed-fully-sf)
[716.101294] Starting subtest: psr2-cursor-plane-move-continuous-exceed-fully-sf
[716.103242] Starting dynamic subtest: pipe-A-eDP-1
[727.084645] Dynamic subtest pipe-A-eDP-1: SUCCESS (10.985s)
[727.095854] Starting dynamic subtest: pipe-B-eDP-1
[738.773862] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.685s)
[738.775805] Subtest psr2-cursor-plane-move-continuous-exceed-fully-sf: SUCCESS (22.673s)
[739.628554] [116/132] (311s left) gem_exec_fence (syncobj-timeline-wait)
[740.196032] Starting subtest: syncobj-timeline-wait
[741.225860] Subtest syncobj-timeline-wait: SUCCESS (1.029s)
[743.273919] [117/132] (307s left) kms_flip (flip-vs-panning-interruptible)
[743.377590] Starting subtest: flip-vs-panning-interruptible
[743.382546] Starting dynamic subtest: A-eDP1
[752.046705] Dynamic subtest A-eDP1: SUCCESS (8.666s)
[752.063279] Starting dynamic subtest: B-eDP1
[760.786242] Dynamic subtest B-eDP1: SUCCESS (8.736s)
[760.798913] Starting dynamic subtest: C-eDP1
[769.513743] Dynamic subtest C-eDP1: SUCCESS (8.723s)
[769.525339] Starting dynamic subtest: D-eDP1
[778.244547] Dynamic subtest D-eDP1: SUCCESS (8.727s)
[778.248705] Subtest flip-vs-panning-interruptible: SUCCESS (34.863s)
[779.425754] [118/132] (271s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-pwrite)
[779.534576] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-pwrite
[779.538975] Subtest fbc-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[779.654606] [119/132] (271s left) perf_pmu (busy-double-start)
[779.698456] Starting subtest: busy-double-start
[779.700755] Starting dynamic subtest: rcs0
[783.334367] Dynamic subtest rcs0: FAIL (3.570s)
[783.348168] Starting dynamic subtest: bcs0
[786.837087] Dynamic subtest bcs0: SUCCESS (3.566s)
[786.849500] Starting dynamic subtest: vcs0
[790.687395] Dynamic subtest vcs0: SUCCESS (3.848s)
[790.700010] Starting dynamic subtest: vcs1
[794.701555] Dynamic subtest vcs1: SUCCESS (4.013s)
[794.713752] Starting dynamic subtest: vecs0
[798.668584] Dynamic subtest vecs0: SUCCESS (3.966s)
[798.679860] Starting dynamic subtest: ccs0
[802.303757] Dynamic subtest ccs0: FAIL (3.567s)
[802.305858] Subtest busy-double-start: FAIL (22.541s)
[802.349091] [120/132] (248s left) kms_plane (pixel-format-4-tiled-lnl-ccs-modifier)
[802.456626] Starting subtest: pixel-format-4-tiled-lnl-ccs-modifier
[802.460869] Subtest pixel-format-4-tiled-lnl-ccs-modifier: SKIP (0.000s)
[802.548376] [121/132] (248s left) gem_spin_batch (spin-all)
[802.608019] Starting subtest: spin-all
[802.621084] Subtest spin-all: SUCCESS (0.013s)
[804.220560] [122/132] (247s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions)
[804.349652] Starting subtest: short-busy-flip-before-cursor-atomic-transitions
[804.353959] Subtest short-busy-flip-before-cursor-atomic-transitions: SKIP (0.000s)
[804.494948] [123/132] (246s left) api_intel_bb (destroy-bb)
[804.546697] Starting subtest: destroy-bb
[804.555406] Subtest destroy-bb: SUCCESS (0.009s)
[804.676502] [124/132] (246s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-gtt)
[804.779047] Starting subtest: fbc-rgb565-draw-mmap-gtt
[804.783060] Subtest fbc-rgb565-draw-mmap-gtt: SKIP (0.000s)
[804.873227] [125/132] (246s left) perf_pmu (busy-idle-no-semaphores)
[804.920106] Starting subtest: busy-idle-no-semaphores
[804.922360] Starting dynamic subtest: rcs0
[805.571310] Dynamic subtest rcs0: SUCCESS (0.652s)
[805.584407] Starting dynamic subtest: bcs0
[806.224661] Dynamic subtest bcs0: SUCCESS (0.651s)
[806.237301] Starting dynamic subtest: vcs0
[806.876182] Dynamic subtest vcs0: SUCCESS (0.649s)
[806.889451] Starting dynamic subtest: vcs1
[808.758324] Dynamic subtest vcs1: SUCCESS (1.880s)
[808.770851] Starting dynamic subtest: vecs0
[810.807243] Dynamic subtest vecs0: SUCCESS (2.047s)
[810.821494] Starting dynamic subtest: ccs0
[812.791251] Dynamic subtest ccs0: SUCCESS (1.982s)
[812.794367] Subtest busy-idle-no-semaphores: SUCCESS (7.874s)
[812.916665] [126/132] (238s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-move)
[813.023520] Starting subtest: fbc-2p-primscrn-cur-indfb-move
[813.027571] Subtest fbc-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[813.108895] [127/132] (238s left) kms_cursor_crc (cursor-random-32x32)
[813.256878] Starting subtest: cursor-random-32x32
[813.318778] Subtest cursor-random-32x32: SKIP (0.057s)
[813.620639] [128/132] (238s left) gem_exec_balancer (bonded-true-hang)
[813.884197] Starting subtest: bonded-true-hang
[813.889322] Subtest bonded-true-hang: SKIP (0.000s)
[813.978404] [129/132] (237s left) gem_ctx_shared (exec-single-timeline)
[814.051717] Starting subtest: exec-single-timeline
[814.054275] Starting dynamic subtest: rcs0
[815.084101] Dynamic subtest rcs0: SUCCESS (1.030s)
[815.095876] Starting dynamic subtest: bcs0
[816.172544] Dynamic subtest bcs0: SUCCESS (1.085s)
[816.190339] Starting dynamic subtest: vcs0
[817.260808] Dynamic subtest vcs0: SUCCESS (1.085s)
[817.278055] Starting dynamic subtest: vcs1
[818.348056] Dynamic subtest vcs1: SUCCESS (1.085s)
[818.361616] Starting dynamic subtest: vecs0
[819.437037] Dynamic subtest vecs0: SUCCESS (1.086s)
[819.451007] Starting dynamic subtest: ccs0
[820.525253] Dynamic subtest ccs0: SUCCESS (1.085s)
[820.528468] Subtest exec-single-timeline: SUCCESS (6.473s)
[821.306194] [130/132] (230s left) kms_big_fb (yf-tiled-16bpp-rotate-90)
[821.403045] Starting subtest: yf-tiled-16bpp-rotate-90
[821.407181] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.000s)
[821.490825] [131/132] (230s left) syncobj_timeline (device-signal-unordered)
[821.546128] Starting subtest: device-signal-unordered
[821.548422] Subtest device-signal-unordered: SUCCESS (0.001s)
[821.647850] [132/132] (230s left) kms_psr2_su (page_flip-nv12)
[821.705994] Subtest page_flip-NV12: SKIP (0.000s)
[821.764265] Closing watchdogs
results: parsing output: 0/ for test: gem_ppgtt
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: gem_busy
results: parsing output: 3/ for test: gem_mmap_offset
results: parsing output: 4/ for test: gem_sync
results: parsing output: 5/ for test: kms_content_protection
results: parsing output: 6/ for test: kms_invalid_mode
results: parsing output: 7/ for test: gem_exec_balancer
results: parsing output: 8/ for test: kms_flip_scaled_crc
results: parsing output: 9/ for test: kms_plane_scaling
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: kms_psr
results: parsing output: 12/ for test: kms_big_fb
results: parsing output: 13/ for test: kms_psr
results: parsing output: 14/ for test: kms_cursor_legacy
results: parsing output: 15/ for test: kms_cdclk
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: gem_mmap_gtt
results: parsing output: 18/ for test: gem_ctx_isolation
results: parsing output: 19/ for test: sw_sync
results: parsing output: 20/ for test: kms_psr
results: parsing output: 21/ for test: kms_psr2_sf
results: parsing output: 22/ for test: gem_lmem_swapping
results: parsing output: 23/ for test: gem_render_copy
results: parsing output: 24/ for test: kms_pm_rpm
results: parsing output: 25/ for test: kms_ccs
results: parsing output: 26/ for test: kms_cursor_legacy
results: parsing output: 27/ for test: kms_big_fb
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: gem_exec_fence
results: parsing output: 30/ for test: kms_pipe_crc_basic
results: parsing output: 31/ for test: api_intel_allocator
results: parsing output: 32/ for test: gem_render_copy
results: parsing output: 33/ for test: kms_ccs
results: parsing output: 34/ for test: kms_big_fb
results: parsing output: 35/ for test: gem_create
results: parsing output: 36/ for test: i915_query
results: parsing output: 37/ for test: gem_exec_reloc
results: parsing output: 38/ for test: kms_color_pipeline
results: parsing output: 39/ for test: gem_mmap_gtt
results: parsing output: 40/ for test: kms_prime
results: parsing output: 41/ for test: gen9_exec_parse
results: parsing output: 42/ for test: kms_plane
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: kms_psr
results: parsing output: 45/ for test: sysfs_timeslice_duration
results: parsing output: 46/ for test: core_hotunplug
results: parsing output: 47/ for test: gem_render_copy
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: kms_ccs
results: parsing output: 50/ for test: kms_hdr
results: parsing output: 51/ for test: gen9_exec_parse
results: parsing output: 52/ for test: kms_chamelium_frames
results: parsing output: 53/ for test: kms_flip_scaled_crc
results: parsing output: 54/ for test: syncobj_timeline
results: parsing output: 55/ for test: kms_properties
results: parsing output: 56/ for test: gem_bad_reloc
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: kms_cursor_legacy
results: parsing output: 59/ for test: gem_userptr_blits
results: parsing output: 60/ for test: kms_lease
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: gem_exec_schedule
results: parsing output: 63/ for test: kms_async_flips
results: parsing output: 64/ for test: gem_userptr_blits
results: parsing output: 65/ for test: kms_flip
results: parsing output: 66/ for test: kms_feature_discovery
results: parsing output: 67/ for test: gem_ctx_param
results: parsing output: 68/ for test: gem_exec_schedule
results: parsing output: 69/ for test: i915_pm_rpm
results: parsing output: 70/ for test: syncobj_timeline
results: parsing output: 71/ for test: kms_cursor_crc
results: parsing output: 72/ for test: sriov_basic
results: parsing output: 73/ for test: kms_big_fb
results: parsing output: 74/ for test: kms_flip
results: parsing output: 75/ for test: kms_plane
results: parsing output: 76/ for test: gem_exec_whisper
results: parsing output: 77/ for test: perf
results: parsing output: 78/ for test: kms_vblank
results: parsing output: 79/ for test: syncobj_wait
results: parsing output: 80/ for test: kms_atomic_interruptible
results: parsing output: 81/ for test: i915_hangman
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: syncobj_timeline
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: kms_big_fb
results: parsing output: 86/ for test: kms_cursor_legacy
results: parsing output: 87/ for test: gem_ctx_bad_destroy
results: parsing output: 88/ for test: gem_softpin
results: parsing output: 89/ for test: kms_setmode
results: parsing output: 90/ for test: kms_flip
results: parsing output: 91/ for test: kms_atomic_transition
results: parsing output: 92/ for test: kms_cursor_legacy
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: syncobj_timeline
results: parsing output: 95/ for test: kms_ccs
results: parsing output: 96/ for test: kms_cursor_crc
results: parsing output: 97/ for test: kms_chamelium_hpd
results: parsing output: 98/ for test: kms_flip
results: parsing output: 99/ for test: kms_psr
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: gem_userptr_blits
results: parsing output: 102/ for test: kms_async_flips
results: parsing output: 103/ for test: gem_mmap_gtt
results: parsing output: 104/ for test: syncobj_basic
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: kms_universal_plane
results: parsing output: 107/ for test: gem_ctx_persistence
results: parsing output: 108/ for test: gem_exec_params
results: parsing output: 109/ for test: kms_big_fb
results: parsing output: 110/ for test: kms_addfb_basic
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: gem_exec_reloc
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: kms_psr2_sf
results: parsing output: 115/ for test: gem_exec_fence
results: parsing output: 116/ for test: kms_flip
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: perf_pmu
results: parsing output: 119/ for test: kms_plane
results: parsing output: 120/ for test: gem_spin_batch
results: parsing output: 121/ for test: kms_cursor_legacy
results: parsing output: 122/ for test: api_intel_bb
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: perf_pmu
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: kms_cursor_crc
results: parsing output: 127/ for test: gem_exec_balancer
results: parsing output: 128/ for test: gem_ctx_shared
results: parsing output: 129/ for test: kms_big_fb
results: parsing output: 130/ for test: syncobj_timeline
results: parsing output: 131/ for test: kms_psr2_su
Done.