[57.894053] Initializing watchdogs
[57.894103]   /dev/watchdog0
[57.909723] [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.914370] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.916661] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.920654] [001/133] (960s left) kms_atomic_transition (plane-all-transition-fencing)
[58.041198] Starting subtest: plane-all-transition-fencing
[58.043700] Starting dynamic subtest: pipe-A-eDP-1
[60.627017] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.544s)
[60.942983] Starting dynamic subtest: pipe-B-eDP-1
[64.261752] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.290s)
[64.566672] Subtest plane-all-transition-fencing: SUCCESS (6.438s)
[65.396894] [002/133] (953s left) kms_ccs (bad-rotation-90-4-tiled-lnl-ccs)
[65.491126] Starting subtest: bad-rotation-90-4-tiled-lnl-ccs
[65.495777] Subtest bad-rotation-90-4-tiled-lnl-ccs: SKIP (0.000s)
[66.633899] [003/133] (951s left) kms_lease (lease-get)
[66.735602] Starting subtest: lease-get
[66.738030] Starting dynamic subtest: pipe-A-eDP-1
[66.740335] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[66.748284] Starting dynamic subtest: pipe-B-eDP-1
[66.750146] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[66.756959] Starting dynamic subtest: pipe-C-eDP-1
[66.758934] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[66.765968] Starting dynamic subtest: pipe-D-eDP-1
[66.768078] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[66.769950] Subtest lease-get: SUCCESS (0.007s)
[66.834722] [004/133] (951s left) gem_exec_whisper (basic-contexts-forked-all)
[66.907688] Starting subtest: basic-contexts-forked-all
[87.273262] Subtest basic-contexts-forked-all: SUCCESS (20.320s)
[87.452812] [005/133] (931s left) perf_pmu (pmu-read)
[87.490807] Starting subtest: pmu-read
[87.493653] Subtest pmu-read: SUCCESS (0.002s)
[87.600202] [006/133] (931s left) kms_prime (basic-modeset-hybrid)
[87.682177] Subtest basic-modeset-hybrid: SKIP (0.000s)
[87.877213] [FACT kms_prime (basic-modeset-hybrid)] new: kernel.kmod_is_loaded.vgem: true
[87.880551] [007/133] (930s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted)
[87.948404] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted
[87.950386] Subtest multi-wait-for-submit-available-unsubmitted-submitted: SUCCESS (0.001s)
[88.043887] [008/133] (930s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[88.145143] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[88.149178] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[88.246168] [009/133] (930s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-wc)
[88.351073] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-wc
[88.355058] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[88.453265] [010/133] (930s left) kms_ccs (ccs-on-another-bo-yf-tiled-ccs)
[88.539794] Starting subtest: ccs-on-another-bo-yf-tiled-ccs
[88.541821] Starting dynamic subtest: pipe-A-eDP-1
[88.561385] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[88.572117] Starting dynamic subtest: pipe-B-eDP-1
[88.590335] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[88.598171] Starting dynamic subtest: pipe-C-eDP-1
[88.615914] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[88.624312] Starting dynamic subtest: pipe-D-eDP-1
[88.641799] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[88.645318] Subtest ccs-on-another-bo-yf-tiled-ccs: SKIP (0.003s)
[90.834726] [011/133] (927s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-cpu)
[90.942988] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-cpu
[90.947142] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[91.045528] [012/133] (927s left) gem_partial_pwrite_pread (reads-uncached)
[91.103703] Subtest reads-uncached: SKIP (0.000s)
[91.173239] [013/133] (927s left) gem_eio (in-flight-immediate)
[91.390444] Starting subtest: in-flight-immediate
[100.117965] Subtest in-flight-immediate: SUCCESS (8.721s)
[100.264361] [014/133] (918s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-blt)
[100.374875] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-blt
[104.078214] Subtest fbc-1p-primscrn-cur-indfb-draw-blt: SUCCESS (3.705s)
[105.260437] [015/133] (913s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-blt)
[105.360087] Starting subtest: fbcpsr-rgb565-draw-blt
[112.548042] Subtest fbcpsr-rgb565-draw-blt: SUCCESS (7.190s)
[112.682446] [016/133] (906s left) gem_exec_capture (capture-recoverable)
[112.742860] Starting subtest: capture-recoverable
[112.748099] Subtest capture-recoverable: SUCCESS (0.003s)
[112.867931] [017/133] (906s left) kms_draw_crc (draw-method-mmap-gtt)
[112.958366] Starting subtest: draw-method-mmap-gtt
[112.962494] Subtest draw-method-mmap-gtt: SKIP (0.000s)
[113.047402] [018/133] (906s left) kms_flip (nonexisting-fb)
[113.146096] Starting subtest: nonexisting-fb
[113.147997] Starting dynamic subtest: A-eDP1
[114.373955] Dynamic subtest A-eDP1: SUCCESS (1.229s)
[114.385710] Starting dynamic subtest: B-eDP1
[115.604526] Dynamic subtest B-eDP1: SUCCESS (1.228s)
[115.614310] Starting dynamic subtest: C-eDP1
[116.741306] Dynamic subtest C-eDP1: SUCCESS (1.136s)
[116.752474] Starting dynamic subtest: D-eDP1
[117.890139] Dynamic subtest D-eDP1: SUCCESS (1.148s)
[117.895061] Subtest nonexisting-fb: SUCCESS (4.749s)
[119.065581] [019/133] (900s left) gem_exec_schedule (preempt-self)
[119.139886] Starting subtest: preempt-self
[119.142734] Starting dynamic subtest: rcs0
[119.156281] Dynamic subtest rcs0: SUCCESS (0.023s)
[119.167691] Starting dynamic subtest: bcs0
[119.177723] Dynamic subtest bcs0: SUCCESS (0.020s)
[119.186933] Starting dynamic subtest: vcs0
[119.199979] Dynamic subtest vcs0: SUCCESS (0.022s)
[119.209142] Starting dynamic subtest: vcs1
[119.240037] Dynamic subtest vcs1: SUCCESS (0.039s)
[119.249270] Starting dynamic subtest: vecs0
[119.263118] Dynamic subtest vecs0: SUCCESS (0.021s)
[119.274723] Starting dynamic subtest: ccs0
[119.285868] Dynamic subtest ccs0: SUCCESS (0.023s)
[119.288148] Subtest preempt-self: SUCCESS (0.154s)
[120.422847] [020/133] (898s left) sysfs_timeslice_duration (idempotent)
[120.479462] Starting subtest: idempotent
[120.481707] Starting dynamic subtest: vecs0
[120.484069] Dynamic subtest vecs0: SUCCESS (0.000s)
[120.493114] Starting dynamic subtest: ccs0
[120.494892] Dynamic subtest ccs0: SUCCESS (0.000s)
[120.503485] Starting dynamic subtest: vcs0
[120.505335] Dynamic subtest vcs0: SUCCESS (0.001s)
[120.513891] Starting dynamic subtest: bcs0
[120.515788] Dynamic subtest bcs0: SUCCESS (0.000s)
[120.524593] Starting dynamic subtest: rcs0
[120.526759] Dynamic subtest rcs0: SUCCESS (0.000s)
[120.535420] Starting dynamic subtest: vcs1
[120.537336] Dynamic subtest vcs1: SUCCESS (0.000s)
[120.539255] Subtest idempotent: SUCCESS (0.005s)
[120.582509] [021/133] (898s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[120.674717] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[120.678975] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[120.776221] [022/133] (898s left) sw_sync (alloc_timeline)
[120.806191] Starting subtest: alloc_timeline
[120.808912] Subtest alloc_timeline: SUCCESS (0.000s)
[120.857710] [023/133] (898s left) gem_exec_reloc (basic-cpu-wc-active)
[120.917124] Subtest basic-cpu-wc-active: SKIP (0.000s)
[120.987687] [024/133] (898s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-blt)
[121.083224] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-blt
[127.976934] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (6.895s)
[128.123444] [025/133] (891s left) gem_exec_reloc (basic-gtt-active)
[128.179298] Subtest basic-gtt-active: SKIP (0.000s)
[128.249469] [026/133] (891s left) kms_atomic (plane-primary-overlay-mutable-zpos)
[128.341340] Starting subtest: plane-primary-overlay-mutable-zpos
[131.832263] Subtest plane-primary-overlay-mutable-zpos: SKIP (3.490s)
[133.020634] [027/133] (886s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-exceed-fully-sf)
[133.132121] Starting subtest: psr2-cursor-plane-move-continuous-exceed-fully-sf
[133.134383] Starting dynamic subtest: pipe-A-eDP-1
[144.166844] Dynamic subtest pipe-A-eDP-1: SUCCESS (11.036s)
[144.177654] Starting dynamic subtest: pipe-B-eDP-1
[155.917296] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.748s)
[155.919610] Subtest psr2-cursor-plane-move-continuous-exceed-fully-sf: SUCCESS (22.786s)
[156.784017] [028/133] (862s left) kms_psr (fbc-pr-cursor-blt)
[156.889058] Starting subtest: fbc-pr-cursor-blt
[156.893410] Subtest fbc-pr-cursor-blt: SKIP (0.000s)
[156.962939] [029/133] (862s left) kms_atomic_transition (plane-primary-toggle-with-vblank-wait)
[157.049643] Starting subtest: plane-primary-toggle-with-vblank-wait
[157.051868] Starting dynamic subtest: pipe-A-eDP-1
[157.224793] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.176s)
[157.548238] Starting dynamic subtest: pipe-B-eDP-1
[158.490518] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.956s)
[158.788647] Subtest plane-primary-toggle-with-vblank-wait: SUCCESS (1.740s)
[159.658975] [030/133] (859s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-gtt)
[159.767806] Starting subtest: psr-rgb565-draw-mmap-gtt
[159.771989] Subtest psr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[159.885228] [031/133] (859s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0)
[159.992102] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0
[161.037167] Subtest x-tiled-max-hw-stride-64bpp-rotate-0: SUCCESS (1.046s)
[161.165721] [032/133] (858s left) kms_plane_scaling (invalid-num-scalers)
[161.258331] Starting subtest: invalid-num-scalers
[161.260199] Starting dynamic subtest: pipe-A-eDP-1-invalid-num-scalers
[161.279482] Dynamic subtest pipe-A-eDP-1-invalid-num-scalers: SUCCESS (0.020s)
[161.291131] Starting dynamic subtest: pipe-B-eDP-1-invalid-num-scalers
[161.314273] Dynamic subtest pipe-B-eDP-1-invalid-num-scalers: SUCCESS (0.034s)
[161.323397] Starting dynamic subtest: pipe-C-eDP-1-invalid-num-scalers
[161.348813] Dynamic subtest pipe-C-eDP-1-invalid-num-scalers: SUCCESS (0.034s)
[161.357995] Starting dynamic subtest: pipe-D-eDP-1-invalid-num-scalers
[161.385168] Dynamic subtest pipe-D-eDP-1-invalid-num-scalers: SUCCESS (0.034s)
[161.387077] Subtest invalid-num-scalers: SUCCESS (0.126s)
[161.494827] [033/133] (858s left) i915_pm_rpm (system-suspend-devices)
[163.524330] Starting subtest: system-suspend-devices
[171.333928] Subtest system-suspend-devices: SUCCESS (7.812s)
[179.767984] [034/133] (847s left) gem_mmap_gtt (cpuset-basic-small-copy-xy)
[179.833818] Subtest cpuset-basic-small-copy-XY: SKIP (0.000s)
[179.889970] [035/133] (847s left) syncobj_timeline (single-wait-all-signaled)
[179.935186] Starting subtest: single-wait-all-signaled
[179.937534] Subtest single-wait-all-signaled: SUCCESS (0.000s)
[180.035967] [036/133] (847s left) kms_force_connector_basic (force-edid)
[180.116501] Starting subtest: force-edid
[180.490506] Subtest force-edid: SUCCESS (0.376s)
[180.633802] [037/133] (847s left) gem_huc_copy (huc-copy)
[180.684584] Starting subtest: huc-copy
[180.689583] Subtest huc-copy: SUCCESS (0.003s)
[180.813139] [038/133] (847s left) kms_chamelium_frames (hdmi-cmp-planar-formats)
[181.191292] Subtest hdmi-cmp-planar-formats: SKIP (0.000s)
[182.021352] [039/133] (845s left) gem_exec_reloc (basic-range)
[182.080529] Subtest basic-range: SKIP (0.000s)
[182.149771] [040/133] (845s left) vgem_slow (nohang)
[182.185668] Starting subtest: nohang
[192.508896] Subtest nohang: SUCCESS (10.326s)
[192.591336] [041/133] (835s left) kms_content_protection (suspend-resume)
[192.732852] Starting subtest: suspend-resume
[192.737368] Subtest suspend-resume: SKIP (0.000s)
[192.864372] [042/133] (835s left) kms_dirtyfb (fbc-dirtyfb-ioctl)
[192.956065] Starting subtest: fbc-dirtyfb-ioctl
[192.958411] Starting dynamic subtest: A-eDP-1
[195.598803] Dynamic subtest A-eDP-1: SUCCESS (2.641s)
[195.600808] Subtest fbc-dirtyfb-ioctl: SUCCESS (2.642s)
[196.447373] [043/133] (831s left) gem_create (create-size-update)
[196.495060] Starting subtest: create-size-update
[196.497367] Subtest create-size-update: SUCCESS (0.000s)
[196.703860] [044/133] (831s left) syncobj_wait (multi-wait-all-for-submit-submitted-signaled)
[196.758753] Starting subtest: multi-wait-all-for-submit-submitted-signaled
[196.866236] Subtest multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.108s)
[196.947978] [045/133] (831s left) syncobj_wait (wait-for-submit-complex)
[196.992684] Starting subtest: wait-for-submit-complex
[197.056110] Subtest wait-for-submit-complex: SUCCESS (0.061s)
[197.150368] [046/133] (831s left) i915_suspend (fence-restore-tiled2untiled)
[197.204035] Starting subtest: fence-restore-tiled2untiled
[197.208692] Subtest fence-restore-tiled2untiled: SKIP (0.000s)
[197.307771] [047/133] (831s left) kms_ccs (crc-sprite-planes-basic-4-tiled-bmg-ccs)
[197.397753] Starting subtest: crc-sprite-planes-basic-4-tiled-bmg-ccs
[197.402171] Subtest crc-sprite-planes-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[198.553862] [048/133] (829s left) api_intel_bb (blit-noreloc-keep-cache)
[198.601989] Starting subtest: blit-noreloc-keep-cache
[198.612241] Subtest blit-noreloc-keep-cache: SUCCESS (0.013s)
[198.735885] [049/133] (829s left) kms_async_flips (async-flip-dpms)
[198.814871] Starting subtest: async-flip-dpms
[199.190362] Starting dynamic subtest: pipe-A-eDP-1
[203.102806] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.911s)
[203.492147] Starting dynamic subtest: pipe-B-eDP-1
[206.406898] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.920s)
[206.787714] Starting dynamic subtest: pipe-C-eDP-1
[209.692079] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.911s)
[210.068395] Starting dynamic subtest: pipe-D-eDP-1
[212.987505] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.927s)
[212.990022] Subtest async-flip-dpms: SUCCESS (14.174s)
[214.247991] [050/133] (814s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[214.355302] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt
[214.359240] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[214.463843] [051/133] (814s left) gem_exec_schedule (fifo)
[214.536887] Starting subtest: fifo
[214.539122] Starting dynamic subtest: rcs0
[214.640701] Dynamic subtest rcs0: SUCCESS (0.109s)
[214.654725] Starting dynamic subtest: vcs0
[214.727059] Dynamic subtest vcs0: SUCCESS (0.085s)
[214.740895] Starting dynamic subtest: vcs1
[214.805665] Dynamic subtest vcs1: SUCCESS (0.078s)
[214.818803] Starting dynamic subtest: vecs0
[214.881049] Dynamic subtest vecs0: SUCCESS (0.074s)
[214.893990] Starting dynamic subtest: ccs0
[214.961787] Dynamic subtest ccs0: SUCCESS (0.080s)
[214.964226] Subtest fifo: SUCCESS (0.431s)
[216.387518] [052/133] (812s left) kms_psr (pr-cursor-mmap-gtt)
[216.484109] Starting subtest: pr-cursor-mmap-gtt
[216.488558] Subtest pr-cursor-mmap-gtt: SKIP (0.000s)
[216.561371] [053/133] (812s left) gem_exec_reloc (basic-cpu-read-noreloc)
[216.619412] Subtest basic-cpu-read-noreloc: SKIP (0.000s)
[216.695909] [054/133] (811s left) gem_userptr_blits (sd-probe)
[216.764116] Starting subtest: sd-probe
[216.766265] Subtest sd-probe: SUCCESS (0.000s)
[216.899184] [055/133] (811s left) kms_cursor_crc (cursor-offscreen-512x170)
[217.188841] Starting subtest: cursor-offscreen-512x170
[217.193627] Subtest cursor-offscreen-512x170: SKIP (0.001s)
[217.317491] [056/133] (811s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-pwrite)
[217.405209] Starting subtest: psr-2p-primscrn-cur-indfb-draw-pwrite
[217.408484] Subtest psr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[217.505686] [057/133] (811s left) kms_vblank (wait-idle-hang)
[217.593274] Starting subtest: wait-idle-hang
[217.595132] Starting dynamic subtest: pipe-A-eDP-1
[244.072791] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.475s)
[244.087206] Starting dynamic subtest: pipe-D-eDP-1
[268.127185] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.049s)
[268.129314] Subtest wait-idle-hang: SUCCESS (50.531s)
[268.942813] [058/133] (759s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted)
[269.008545] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted
[269.115133] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.105s)
[269.204875] [059/133] (759s left) kms_atomic_interruptible (legacy-cursor)
[269.293812] Starting subtest: legacy-cursor
[269.296064] Starting dynamic subtest: pipe-A-eDP-1
[276.725279] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.428s)
[276.727600] Subtest legacy-cursor: SUCCESS (7.430s)
[277.544483] [060/133] (751s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-upscaling)
[277.642109] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-upscaling
[277.644376] Starting dynamic subtest: pipe-A-default-mode
[280.319410] Dynamic subtest pipe-A-default-mode: SUCCESS (2.675s)
[280.321348] Subtest flip-32bpp-4tile-to-64bpp-4tile-upscaling: SUCCESS (2.677s)
[281.147315] [061/133] (747s left) kms_color_pipeline (plane-ctm3x4)
[281.232666] Starting subtest: plane-ctm3x4
[281.379276] Starting dynamic subtest: pipe-A-plane-0
[281.523052] Dynamic subtest pipe-A-plane-0: SUCCESS (0.144s)
[281.533815] Starting dynamic subtest: pipe-A-plane-1
[281.679776] Dynamic subtest pipe-A-plane-1: SUCCESS (0.155s)
[281.693240] Starting dynamic subtest: pipe-A-plane-2
[281.813623] Dynamic subtest pipe-A-plane-2: SUCCESS (0.133s)
[283.025745] Starting dynamic subtest: pipe-B-plane-0
[283.196665] Dynamic subtest pipe-B-plane-0: SUCCESS (0.177s)
[283.207550] Starting dynamic subtest: pipe-B-plane-1
[283.349901] Dynamic subtest pipe-B-plane-1: SUCCESS (0.155s)
[283.361321] Starting dynamic subtest: pipe-B-plane-2
[283.483982] Dynamic subtest pipe-B-plane-2: SUCCESS (0.132s)
[284.699645] Starting dynamic subtest: pipe-C-plane-0
[284.824126] Dynamic subtest pipe-C-plane-0: SUCCESS (0.133s)
[284.835226] Starting dynamic subtest: pipe-C-plane-1
[284.935054] Dynamic subtest pipe-C-plane-1: SUCCESS (0.110s)
[284.945717] Starting dynamic subtest: pipe-C-plane-2
[285.046212] Dynamic subtest pipe-C-plane-2: SUCCESS (0.110s)
[286.247123] Starting dynamic subtest: pipe-D-plane-0
[286.360200] Dynamic subtest pipe-D-plane-0: SUCCESS (0.122s)
[286.370863] Starting dynamic subtest: pipe-D-plane-1
[286.504874] Dynamic subtest pipe-D-plane-1: SUCCESS (0.144s)
[286.515686] Starting dynamic subtest: pipe-D-plane-2
[286.638680] Dynamic subtest pipe-D-plane-2: SUCCESS (0.132s)
[286.931510] Subtest plane-ctm3x4: SUCCESS (5.697s)
[287.742156] [062/133] (741s left) kms_psr (psr-cursor-blt)
[287.839444] Starting subtest: psr-cursor-blt
[287.841802] Starting dynamic subtest: eDP-1
[289.599106] Dynamic subtest eDP-1: SUCCESS (1.759s)
[289.601361] Subtest psr-cursor-blt: SUCCESS (1.760s)
[290.448135] [063/133] (738s left) gem_mmap_gtt (hang-busy)
[290.499592] Subtest hang-busy: SKIP (0.000s)
[290.559386] [064/133] (738s left) syncobj_timeline (invalid-single-wait-all-unsubmitted)
[290.610760] Starting subtest: invalid-single-wait-all-unsubmitted
[290.613076] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[290.711353] [065/133] (738s left) gem_exec_nop (basic-sequential)
[290.789374] Starting subtest: basic-sequential
[307.403931] Subtest basic-sequential: SUCCESS (16.622s)
[307.637439] [066/133] (721s left) gem_render_copy (y-tiled-ccs-to-yf-tiled-mc-ccs)
[307.696682] Starting subtest: y-tiled-ccs-to-yf-tiled-mc-ccs
[307.701252] Subtest y-tiled-ccs-to-yf-tiled-mc-ccs: SKIP (0.000s)
[307.898894] [067/133] (721s left) kms_frontbuffer_tracking (psr-farfromfence-mmap-gtt)
[307.995557] Starting subtest: psr-farfromfence-mmap-gtt
[307.999981] Subtest psr-farfromfence-mmap-gtt: SKIP (0.000s)
[308.098862] [068/133] (721s left) kms_plane (pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping)
[308.194060] Starting subtest: pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping
[308.221307] Starting dynamic subtest: pipe-A-plane-0
[308.498137] Dynamic subtest pipe-A-plane-0: SUCCESS (0.278s)
[308.508927] Starting dynamic subtest: pipe-A-plane-3
[308.711379] Dynamic subtest pipe-A-plane-3: SUCCESS (0.211s)
[309.801846] Starting dynamic subtest: pipe-B-plane-0
[310.081946] Dynamic subtest pipe-B-plane-0: SUCCESS (0.289s)
[310.098815] Starting dynamic subtest: pipe-B-plane-3
[310.292541] Dynamic subtest pipe-B-plane-3: SUCCESS (0.210s)
[310.613563] Subtest pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping: SUCCESS (2.419s)
[311.450321] [069/133] (717s left) gem_ctx_shared (q-smoketest)
[311.519708] Starting subtest: Q-smoketest
[311.522152] Starting dynamic subtest: rcs0
[317.073263] Dynamic subtest rcs0: SUCCESS (5.500s)
[317.088771] Starting dynamic subtest: bcs0
[322.539186] Dynamic subtest bcs0: SUCCESS (5.467s)
[322.551580] Starting dynamic subtest: vcs0
[328.014618] Dynamic subtest vcs0: SUCCESS (5.476s)
[328.025925] Starting dynamic subtest: vcs1
[333.518310] Dynamic subtest vcs1: SUCCESS (5.502s)
[333.533979] Starting dynamic subtest: vecs0
[339.003486] Dynamic subtest vecs0: SUCCESS (5.482s)
[339.014505] Starting dynamic subtest: ccs0
[344.483518] Dynamic subtest ccs0: SUCCESS (5.479s)
[344.485519] Subtest Q-smoketest: SUCCESS (32.913s)
[344.627127] [070/133] (684s left) gem_pxp (verify-pxp-key-change-after-suspend-resume)
[344.745841] Starting subtest: verify-pxp-key-change-after-suspend-resume
[352.714854] Subtest verify-pxp-key-change-after-suspend-resume: SUCCESS (7.972s)
[361.844451] [071/133] (676s left) kms_flip (absolute-wf_vblank)
[361.950602] Starting subtest: absolute-wf_vblank
[361.952859] Starting dynamic subtest: A-eDP1
[370.664036] Dynamic subtest A-eDP1: SUCCESS (8.715s)
[370.677312] Starting dynamic subtest: B-eDP1
[379.365685] Dynamic subtest B-eDP1: SUCCESS (8.700s)
[379.376212] Starting dynamic subtest: C-eDP1
[388.064487] Dynamic subtest C-eDP1: SUCCESS (8.697s)
[388.075978] Starting dynamic subtest: D-eDP1
[396.769035] Dynamic subtest D-eDP1: SUCCESS (8.703s)
[396.774892] Subtest absolute-wf_vblank: SUCCESS (34.823s)
[397.943509] [072/133] (640s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[398.044658] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt
[398.048995] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[398.161648] [073/133] (640s left) kms_pm_rpm (fences-dpms)
[399.688596] Starting subtest: fences-dpms
[399.693328] Subtest fences-dpms: SKIP (0.000s)
[400.457939] [074/133] (638s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[400.552731] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt
[400.557183] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[400.652564] [075/133] (637s left) kms_ccs (crc-primary-suspend-4-tiled-bmg-ccs)
[400.742030] Starting subtest: crc-primary-suspend-4-tiled-bmg-ccs
[400.746530] Subtest crc-primary-suspend-4-tiled-bmg-ccs: SKIP (0.000s)
[401.838604] [076/133] (636s left) i915_drm_fdinfo (all-busy-check-all)
[401.886131] Starting subtest: all-busy-check-all
[401.892782] Subtest all-busy-check-all: SKIP (0.004s)
[401.967372] [077/133] (636s left) kms_plane_scaling (planes-upscale-factor-0-25)
[402.093175] Starting subtest: planes-upscale-factor-0-25
[402.095398] Starting dynamic subtest: pipe-A
[402.153832] Dynamic subtest pipe-A: SUCCESS (0.063s)
[402.166424] Starting dynamic subtest: pipe-B
[403.329100] Dynamic subtest pipe-B: SUCCESS (1.174s)
[403.341884] Starting dynamic subtest: pipe-C
[404.437863] Dynamic subtest pipe-C: SUCCESS (1.107s)
[404.449460] Starting dynamic subtest: pipe-D
[405.614260] Dynamic subtest pipe-D: SUCCESS (1.176s)
[405.616550] Subtest planes-upscale-factor-0-25: SUCCESS (3.524s)
[406.853283] [078/133] (631s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-180)
[406.953942] Starting subtest: linear-max-hw-stride-64bpp-rotate-180
[408.078401] Subtest linear-max-hw-stride-64bpp-rotate-180: SUCCESS (1.124s)
[408.211248] [079/133] (630s left) sw_sync (sync_multi_timeline_wait)
[408.245538] Starting subtest: sync_multi_timeline_wait
[408.247803] Subtest sync_multi_timeline_wait: SUCCESS (0.000s)
[408.295822] [080/133] (630s left) kms_chamelium_color (ctm-negative)
[408.382319] Subtest ctm-negative: SKIP (0.000s)
[408.430955] [081/133] (630s left) gem_exec_fence (syncobj-signal)
[408.886574] Starting subtest: syncobj-signal
[408.891588] Subtest syncobj-signal: SUCCESS (0.005s)
[410.414432] [082/133] (628s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[410.510486] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip
[410.515021] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[410.608407] [083/133] (628s left) gem_pxp (create-regular-context-2)
[410.712715] Starting subtest: create-regular-context-2
[410.715246] Subtest create-regular-context-2: SUCCESS (0.002s)
[410.828680] [084/133] (628s left) kms_cursor_legacy (cursora-vs-flipb-atomic)
[410.952638] Starting subtest: cursorA-vs-flipB-atomic
[410.957202] Subtest cursorA-vs-flipB-atomic: SKIP (0.001s)
[411.125166] [085/133] (627s left) kms_cursor_crc (cursor-rapid-movement-32x10)
[411.290678] Starting subtest: cursor-rapid-movement-32x10
[411.340333] Subtest cursor-rapid-movement-32x10: SKIP (0.045s)
[411.615649] [086/133] (627s left) gem_eio (unwedge-stress)
[411.838140] Starting subtest: unwedge-stress
[411.840181] Starting dynamic subtest: render
[416.628770] Dynamic subtest render: SUCCESS (4.788s)
[416.647035] Starting dynamic subtest: bsd1
[434.807847] Dynamic subtest bsd1: SUCCESS (18.177s)
[434.826956] Starting dynamic subtest: bsd2
[452.819490] Dynamic subtest bsd2: SUCCESS (18.009s)
[452.838722] Starting dynamic subtest: blt
[452.842966] Dynamic subtest blt: SKIP (0.000s)
[452.853106] Starting dynamic subtest: vebox
[470.789034] Dynamic subtest vebox: SUCCESS (17.965s)
[470.791183] Subtest unwedge-stress: SUCCESS (58.944s)
[472.391357] [087/133] (566s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[472.495359] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip
[472.499603] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[472.606952] [088/133] (566s left) gem_ctx_param (invalid-param-set)
[472.654063] Starting subtest: invalid-param-set
[472.656374] Subtest invalid-param-set: SUCCESS (0.000s)
[472.728470] [089/133] (566s left) kms_frontbuffer_tracking (fbc-suspend)
[472.824790] Starting subtest: fbc-suspend
[510.469856] Subtest fbc-suspend: SUCCESS (8.292s)
[514.691368] [090/133] (527s left) gem_mmap_gtt (basic-read-write-distinct)
[514.751131] Subtest basic-read-write-distinct: SKIP (0.000s)
[514.814592] [091/133] (527s left) kms_psr (psr-primary-mmap-gtt)
[514.911695] Starting subtest: psr-primary-mmap-gtt
[514.913986] Starting dynamic subtest: eDP-1
[516.477054] Dynamic subtest eDP-1: SKIP (1.498s)
[516.481747] Subtest psr-primary-mmap-gtt: SKIP (1.500s)
[516.656861] [092/133] (525s left) i915_pm_freq_api (freq-reset)
[516.714143] Starting subtest: freq-reset
[516.716659] Starting dynamic subtest: gt0
[516.905377] Dynamic subtest gt0: SUCCESS (0.182s)
[516.916786] Starting dynamic subtest: gt1
[517.085015] Dynamic subtest gt1: SUCCESS (0.168s)
[517.087764] Subtest freq-reset: SUCCESS (0.353s)
[517.171467] [093/133] (525s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions)
[517.283628] Starting subtest: cursor-vs-flip-atomic-transitions
[547.870474] Subtest cursor-vs-flip-atomic-transitions: SUCCESS (30.304s)
[548.709901] [094/133] (493s left) syncobj_eventfd (invalid-illegal-eventfd)
[548.772052] Starting subtest: invalid-illegal-eventfd
[548.774358] Subtest invalid-illegal-eventfd: SUCCESS (0.000s)
[548.883936] [095/133] (493s left) kms_sequence (queue-busy)
[548.968390] Starting subtest: queue-busy
[548.970701] Starting dynamic subtest: pipe-A-eDP-1
[550.892811] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.924s)
[550.903715] Starting dynamic subtest: pipe-B-eDP-1
[553.486258] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.589s)
[553.497723] Starting dynamic subtest: pipe-C-eDP-1
[556.064796] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.578s)
[556.077412] Starting dynamic subtest: pipe-D-eDP-1
[558.670650] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.602s)
[558.672950] Subtest queue-busy: SUCCESS (9.700s)
[559.522293] [096/133] (482s left) gem_ctx_persistence (saturated-hostile-nopreempt)
[559.576860] Starting subtest: saturated-hostile-nopreempt
[559.579228] Starting dynamic subtest: rcs0
[559.654862] Dynamic subtest rcs0: SKIP (0.075s)
[559.666538] Starting dynamic subtest: bcs0
[559.671026] Dynamic subtest bcs0: SKIP (0.011s)
[559.681324] Starting dynamic subtest: vcs0
[559.688191] Dynamic subtest vcs0: SKIP (0.020s)
[559.699443] Starting dynamic subtest: vcs1
[559.703988] Dynamic subtest vcs1: SKIP (0.011s)
[559.717277] Starting dynamic subtest: vecs0
[559.721170] Dynamic subtest vecs0: SKIP (0.012s)
[559.732169] Starting dynamic subtest: ccs0
[559.736345] Dynamic subtest ccs0: SKIP (0.016s)
[559.740592] Subtest saturated-hostile-nopreempt: SKIP (0.151s)
[559.813074] [097/133] (482s left) gem_softpin (allocator-basic-reserve)
[559.862321] Starting subtest: allocator-basic-reserve
[559.933510] Subtest allocator-basic-reserve: SUCCESS (0.071s)
[560.046075] [098/133] (482s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-blt)
[560.149181] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-blt
[567.467860] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-blt: SUCCESS (7.316s)
[567.627399] [099/133] (475s left) kms_psr (fbc-psr2-cursor-blt)
[567.715530] Starting subtest: fbc-psr2-cursor-blt
[567.717666] Starting dynamic subtest: eDP-1
[567.721602] Dynamic subtest eDP-1: SKIP (0.000s)
[567.725481] Subtest fbc-psr2-cursor-blt: SKIP (0.001s)
[567.789299] [100/133] (474s left) fbdev (unaligned-write)
[567.829076] Starting subtest: unaligned-write
[567.933128] Subtest unaligned-write: SUCCESS (0.102s)
[568.018306] [101/133] (474s left) gem_exec_reloc (basic-gtt-wc-noreloc)
[568.075133] Subtest basic-gtt-wc-noreloc: SKIP (0.000s)
[568.141622] [102/133] (474s left) kms_prop_blob (blob-prop-validate)
[568.185801] Starting subtest: blob-prop-validate
[568.188033] Subtest blob-prop-validate: SUCCESS (0.000s)
[568.290510] [103/133] (474s left) gem_exec_balancer (full-pulse)
[568.350801] Starting subtest: full-pulse
[573.594445] Subtest full-pulse: SUCCESS (5.241s)
[573.926870] [104/133] (468s left) dumb_buffer (map-uaf)
[573.976935] Starting subtest: map-uaf
[573.978843] Subtest map-uaf: SUCCESS (0.004s)
[574.071084] [105/133] (468s left) api_intel_bb (object-noreloc-keep-cache-simple)
[574.135486] Starting subtest: object-noreloc-keep-cache-simple
[574.147147] Subtest object-noreloc-keep-cache-simple: SUCCESS (0.013s)
[574.237506] [106/133] (468s left) kms_setmode (basic-clone-single-crtc)
[574.288618] Starting subtest: basic-clone-single-crtc
[574.295250] Subtest basic-clone-single-crtc: SKIP (0.000s)
[574.389792] [107/133] (468s left) gem_exec_schedule (preempt-queue-contexts)
[574.461050] Subtest preempt-queue-contexts: SKIP (0.000s)
[574.760422] [108/133] (468s left) gem_exec_reloc (basic-write-cpu-noreloc)
[574.821908] Subtest basic-write-cpu-noreloc: SKIP (0.000s)
[574.895967] [109/133] (468s left) gem_softpin (evict-snoop)
[574.958538] Starting subtest: evict-snoop
[574.963111] Subtest evict-snoop: SKIP (0.000s)
[575.087530] [110/133] (468s left) kms_big_fb (linear-16bpp-rotate-270)
[575.200526] Starting subtest: linear-16bpp-rotate-270
[575.220438] Subtest linear-16bpp-rotate-270: SKIP (0.019s)
[575.340713] [111/133] (467s left) kms_plane_alpha_blend (coverage-7efc)
[575.435886] Starting subtest: coverage-7efc
[575.729589] Starting dynamic subtest: pipe-A-eDP-1
[576.301534] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.569s)
[576.331261] Starting dynamic subtest: pipe-D-eDP-1
[577.616138] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.294s)
[577.618512] Subtest coverage-7efc: SUCCESS (2.182s)
[578.424923] [112/133] (464s left) kms_invalid_mode (clock-too-high)
[578.523351] Starting subtest: clock-too-high
[578.525840] Starting dynamic subtest: pipe-A-eDP-1
[578.810888] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.290s)
[578.822473] Starting dynamic subtest: pipe-B-eDP-1
[578.826812] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[578.837704] Starting dynamic subtest: pipe-C-eDP-1
[578.842184] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[578.853304] Starting dynamic subtest: pipe-D-eDP-1
[578.857866] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[578.860125] Subtest clock-too-high: SUCCESS (0.296s)
[579.635490] [113/133] (463s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[579.736701] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc
[579.741171] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[579.839051] [114/133] (463s left) gem_partial_pwrite_pread (write)
[579.903986] Subtest write: SKIP (0.000s)
[579.981598] [115/133] (463s left) gen7_exec_parse (oacontrol-tracking)
[580.033858] Subtest oacontrol-tracking: SKIP (0.000s)
[580.103428] [116/133] (463s left) prime_busy (hang)
[580.171306] Starting subtest: hang
[580.173621] Starting dynamic subtest: rcs0
[606.184672] Dynamic subtest rcs0: SUCCESS (25.998s)
[606.195515] Starting dynamic subtest: bcs0
[613.338294] Dynamic subtest bcs0: SUCCESS (7.148s)
[613.351158] Starting dynamic subtest: vcs0
[620.309108] Dynamic subtest vcs0: SUCCESS (6.975s)
[620.320571] Starting dynamic subtest: vcs1
[627.352635] Dynamic subtest vcs1: SUCCESS (7.043s)
[627.363698] Starting dynamic subtest: vecs0
[634.326580] Dynamic subtest vecs0: SUCCESS (6.972s)
[634.337057] Starting dynamic subtest: ccs0
[648.231203] Dynamic subtest ccs0: SUCCESS (13.904s)
[648.233439] Subtest hang: SUCCESS (68.052s)
[648.342335] [117/133] (395s left) kms_rotation_crc (primary-y-tiled-reflect-x-0)
[648.443439] Starting subtest: primary-y-tiled-reflect-x-0
[648.503800] Subtest primary-y-tiled-reflect-x-0: SKIP (0.059s)
[648.610064] [118/133] (394s left) kms_plane (pixel-format-4-tiled-bmg-ccs-modifier-source-clamping)
[648.698493] Starting subtest: pixel-format-4-tiled-bmg-ccs-modifier-source-clamping
[648.702650] Subtest pixel-format-4-tiled-bmg-ccs-modifier-source-clamping: SKIP (0.000s)
[648.775414] [119/133] (394s left) kms_busy (extended-modeset-hang-oldfb)
[648.868859] Starting subtest: extended-modeset-hang-oldfb
[648.871160] Starting dynamic subtest: pipe-A
[660.476065] Dynamic subtest pipe-A: SUCCESS (11.602s)
[660.488006] Starting dynamic subtest: pipe-D
[672.780132] Dynamic subtest pipe-D: SUCCESS (12.301s)
[672.782332] Subtest extended-modeset-hang-oldfb: SUCCESS (23.908s)
[673.618780] [120/133] (369s left) kms_flip (2x-blocking-wf_vblank)
[673.722607] Starting subtest: 2x-blocking-wf_vblank
[673.731578] Subtest 2x-blocking-wf_vblank: SKIP (0.011s)
[673.816832] [121/133] (369s left) kms_chamelium_hpd (vga-hpd)
[674.205605] Subtest vga-hpd: SKIP (0.000s)
[675.004486] [122/133] (368s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt)
[675.107270] Starting subtest: fbc-rgb565-draw-blt
[679.277747] Subtest fbc-rgb565-draw-blt: SUCCESS (4.173s)
[680.440154] [123/133] (363s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180)
[680.546635] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180
[681.570796] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180: SUCCESS (1.024s)
[681.705692] [124/133] (362s left) gem_mmap_wc (read-write)
[681.769307] Subtest read-write: SKIP (0.000s)
[681.840641] [125/133] (361s left) gem_workarounds (reset-fd)
[681.906869] Starting subtest: reset-fd
[682.059627] Subtest reset-fd: SUCCESS (0.152s)
[682.200657] [126/133] (361s left) kms_setmode (invalid-clone-exclusive-crtc)
[682.255671] Starting subtest: invalid-clone-exclusive-crtc
[682.261104] Subtest invalid-clone-exclusive-crtc: SKIP (0.000s)
[682.377230] [127/133] (361s left) gem_eio (wait-wedge-1us)
[682.562711] Starting subtest: wait-wedge-1us
[683.716675] Subtest wait-wedge-1us: SUCCESS (1.154s)
[683.845702] [128/133] (360s left) kms_cursor_legacy (forked-bo)
[683.967585] Starting subtest: forked-bo
[683.969838] Starting dynamic subtest: pipe-A
[689.432035] Dynamic subtest pipe-A: SUCCESS (5.459s)
[689.444426] Starting dynamic subtest: pipe-B
[694.932844] Dynamic subtest pipe-B: SUCCESS (5.496s)
[694.955093] Starting dynamic subtest: pipe-C
[700.408362] Dynamic subtest pipe-C: SUCCESS (5.472s)
[700.421940] Starting dynamic subtest: pipe-D
[705.883806] Dynamic subtest pipe-D: SUCCESS (5.471s)
[705.900621] Starting dynamic subtest: all-pipes
[711.399397] Dynamic subtest all-pipes: SUCCESS (5.511s)
[711.401730] Subtest forked-bo: SUCCESS (27.422s)
[711.545698] [129/133] (332s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-upscaling)
[711.653468] Starting subtest: flip-64bpp-linear-to-16bpp-linear-upscaling
[711.655833] Starting dynamic subtest: pipe-A-default-mode
[714.416827] Dynamic subtest pipe-A-default-mode: SUCCESS (2.761s)
[714.418734] Subtest flip-64bpp-linear-to-16bpp-linear-upscaling: SUCCESS (2.762s)
[715.236504] [130/133] (328s left) perf_pmu (busy-double-start)
[715.275025] Starting subtest: busy-double-start
[715.277282] Starting dynamic subtest: rcs0
[718.907040] Dynamic subtest rcs0: FAIL (3.578s)
[718.919205] Starting dynamic subtest: bcs0
[722.489826] Dynamic subtest bcs0: FAIL (3.568s)
[722.504575] Starting dynamic subtest: vcs0
[726.216502] Dynamic subtest vcs0: SUCCESS (3.792s)
[726.228377] Starting dynamic subtest: vcs1
[730.206208] Dynamic subtest vcs1: SUCCESS (3.987s)
[730.218613] Starting dynamic subtest: vecs0
[734.259408] Dynamic subtest vecs0: SUCCESS (4.049s)
[734.273430] Starting dynamic subtest: ccs0
[737.896734] Dynamic subtest ccs0: FAIL (3.566s)
[737.898604] Subtest busy-double-start: FAIL (22.549s)
[737.940738] [131/133] (306s left) gem_ctx_persistence (engines-persistence)
[738.003344] Starting subtest: engines-persistence
[738.005675] Starting dynamic subtest: rcs0
[738.839821] Dynamic subtest rcs0: SUCCESS (0.836s)
[738.856100] Starting dynamic subtest: bcs0
[739.589342] Dynamic subtest bcs0: SUCCESS (0.747s)
[739.603615] Starting dynamic subtest: vcs0
[740.354726] Dynamic subtest vcs0: SUCCESS (0.763s)
[740.368850] Starting dynamic subtest: vcs1
[741.126752] Dynamic subtest vcs1: SUCCESS (0.770s)
[741.140787] Starting dynamic subtest: vecs0
[741.893798] Dynamic subtest vecs0: SUCCESS (0.765s)
[741.908100] Starting dynamic subtest: ccs0
[742.662379] Dynamic subtest ccs0: SUCCESS (0.766s)
[742.665213] Subtest engines-persistence: SUCCESS (4.661s)
[742.760761] [132/133] (301s left) kms_psr (fbc-psr2-sprite-render)
[742.864683] Starting subtest: fbc-psr2-sprite-render
[742.866989] Starting dynamic subtest: eDP-1
[742.871296] Dynamic subtest eDP-1: SKIP (0.000s)
[742.875785] Subtest fbc-psr2-sprite-render: SKIP (0.001s)
[742.947125] [133/133] (301s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[743.051132] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[743.055220] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[743.146690] Closing watchdogs
results: parsing output: 0/ for test: kms_atomic_transition
results: parsing output: 1/ for test: kms_ccs
results: parsing output: 2/ for test: kms_lease
results: parsing output: 3/ for test: gem_exec_whisper
results: parsing output: 4/ for test: perf_pmu
results: parsing output: 5/ for test: kms_prime
results: parsing output: 6/ for test: syncobj_timeline
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: kms_ccs
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: gem_partial_pwrite_pread
results: parsing output: 12/ for test: gem_eio
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: gem_exec_capture
results: parsing output: 16/ for test: kms_draw_crc
results: parsing output: 17/ for test: kms_flip
results: parsing output: 18/ for test: gem_exec_schedule
results: parsing output: 19/ for test: sysfs_timeslice_duration
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: sw_sync
results: parsing output: 22/ for test: gem_exec_reloc
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: gem_exec_reloc
results: parsing output: 25/ for test: kms_atomic
results: parsing output: 26/ for test: kms_psr2_sf
results: parsing output: 27/ for test: kms_psr
results: parsing output: 28/ for test: kms_atomic_transition
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: kms_big_fb
results: parsing output: 31/ for test: kms_plane_scaling
results: parsing output: 32/ for test: i915_pm_rpm
results: parsing output: 33/ for test: gem_mmap_gtt
results: parsing output: 34/ for test: syncobj_timeline
results: parsing output: 35/ for test: kms_force_connector_basic
results: parsing output: 36/ for test: gem_huc_copy
results: parsing output: 37/ for test: kms_chamelium_frames
results: parsing output: 38/ for test: gem_exec_reloc
results: parsing output: 39/ for test: vgem_slow
results: parsing output: 40/ for test: kms_content_protection
results: parsing output: 41/ for test: kms_dirtyfb
results: parsing output: 42/ for test: gem_create
results: parsing output: 43/ for test: syncobj_wait
results: parsing output: 44/ for test: syncobj_wait
results: parsing output: 45/ for test: i915_suspend
results: parsing output: 46/ for test: kms_ccs
results: parsing output: 47/ for test: api_intel_bb
results: parsing output: 48/ for test: kms_async_flips
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: gem_exec_schedule
results: parsing output: 51/ for test: kms_psr
results: parsing output: 52/ for test: gem_exec_reloc
results: parsing output: 53/ for test: gem_userptr_blits
results: parsing output: 54/ for test: kms_cursor_crc
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: kms_vblank
results: parsing output: 57/ for test: syncobj_timeline
results: parsing output: 58/ for test: kms_atomic_interruptible
results: parsing output: 59/ for test: kms_flip_scaled_crc
results: parsing output: 60/ for test: kms_color_pipeline
results: parsing output: 61/ for test: kms_psr
results: parsing output: 62/ for test: gem_mmap_gtt
results: parsing output: 63/ for test: syncobj_timeline
results: parsing output: 64/ for test: gem_exec_nop
results: parsing output: 65/ for test: gem_render_copy
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: kms_plane
results: parsing output: 68/ for test: gem_ctx_shared
results: parsing output: 69/ for test: gem_pxp
results: parsing output: 70/ for test: kms_flip
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: kms_pm_rpm
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: kms_ccs
results: parsing output: 75/ for test: i915_drm_fdinfo
results: parsing output: 76/ for test: kms_plane_scaling
results: parsing output: 77/ for test: kms_big_fb
results: parsing output: 78/ for test: sw_sync
results: parsing output: 79/ for test: kms_chamelium_color
results: parsing output: 80/ for test: gem_exec_fence
results: parsing output: 81/ for test: kms_big_fb
results: parsing output: 82/ for test: gem_pxp
results: parsing output: 83/ for test: kms_cursor_legacy
results: parsing output: 84/ for test: kms_cursor_crc
results: parsing output: 85/ for test: gem_eio
results: parsing output: 86/ for test: kms_big_fb
results: parsing output: 87/ for test: gem_ctx_param
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: gem_mmap_gtt
results: parsing output: 90/ for test: kms_psr
results: parsing output: 91/ for test: i915_pm_freq_api
results: parsing output: 92/ for test: kms_cursor_legacy
results: parsing output: 93/ for test: syncobj_eventfd
results: parsing output: 94/ for test: kms_sequence
results: parsing output: 95/ for test: gem_ctx_persistence
results: parsing output: 96/ for test: gem_softpin
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: kms_psr
results: parsing output: 99/ for test: fbdev
results: parsing output: 100/ for test: gem_exec_reloc
results: parsing output: 101/ for test: kms_prop_blob
results: parsing output: 102/ for test: gem_exec_balancer
results: parsing output: 103/ for test: dumb_buffer
results: parsing output: 104/ for test: api_intel_bb
results: parsing output: 105/ for test: kms_setmode
results: parsing output: 106/ for test: gem_exec_schedule
results: parsing output: 107/ for test: gem_exec_reloc
results: parsing output: 108/ for test: gem_softpin
results: parsing output: 109/ for test: kms_big_fb
results: parsing output: 110/ for test: kms_plane_alpha_blend
results: parsing output: 111/ for test: kms_invalid_mode
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: gem_partial_pwrite_pread
results: parsing output: 114/ for test: gen7_exec_parse
results: parsing output: 115/ for test: prime_busy
results: parsing output: 116/ for test: kms_rotation_crc
results: parsing output: 117/ for test: kms_plane
results: parsing output: 118/ for test: kms_busy
results: parsing output: 119/ for test: kms_flip
results: parsing output: 120/ for test: kms_chamelium_hpd
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: kms_big_fb
results: parsing output: 123/ for test: gem_mmap_wc
results: parsing output: 124/ for test: gem_workarounds
results: parsing output: 125/ for test: kms_setmode
results: parsing output: 126/ for test: gem_eio
results: parsing output: 127/ for test: kms_cursor_legacy
results: parsing output: 128/ for test: kms_flip_scaled_crc
results: parsing output: 129/ for test: perf_pmu
results: parsing output: 130/ for test: gem_ctx_persistence
results: parsing output: 131/ for test: kms_psr
results: parsing output: 132/ for test: kms_frontbuffer_tracking
Done.