[40.233791] Initializing watchdogs
[40.234069]   /dev/watchdog0
[40.268256] [001/141] (960s left) kms_getfb (getfb2-handle-protection)
[40.610121] Starting subtest: getfb2-handle-protection
[40.630512] Subtest getfb2-handle-protection: SUCCESS (0.045s)
[40.764318] [002/141] (960s left) kms_big_fb (4-tiled-addfb-size-offset-overflow)
[41.188944] Starting subtest: 4-tiled-addfb-size-offset-overflow
[41.226532] Subtest 4-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[41.333185] [003/141] (959s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc)
[41.764927] Starting subtest: psr-rgb565-draw-mmap-wc
[41.808237] Subtest psr-rgb565-draw-mmap-wc: SKIP (0.001s)
[41.910838] [004/141] (959s left) kms_plane (plane-position-hole)
[42.270466] Starting subtest: plane-position-hole
[42.289045] Starting dynamic subtest: pipe-A-planes
[43.890223] Dynamic subtest pipe-A-planes: SUCCESS (1.607s)
[43.919929] Starting dynamic subtest: pipe-B-planes
[45.690658] Dynamic subtest pipe-B-planes: SUCCESS (1.783s)
[45.714173] Subtest plane-position-hole: SUCCESS (3.395s)
[46.067713] [005/141] (955s left) gem_mmap (short-mmap)
[46.399892] Starting subtest: short-mmap
[46.420997] Subtest short-mmap: SUCCESS (0.000s)
[46.520788] [006/141] (954s left) i915_hangman (hangcheck-unterminated)
[46.827526] Starting subtest: hangcheck-unterminated
[56.433475] Subtest hangcheck-unterminated: SUCCESS (9.477s)
[56.541597] [007/141] (944s left) kms_flip (dpms-off-confusion)
[56.924295] Starting subtest: dpms-off-confusion
[56.946283] Starting dynamic subtest: A-DP1
[67.734314] Dynamic subtest A-DP1: SUCCESS (10.833s)
[67.768306] Starting dynamic subtest: B-DP1
[78.195976] Dynamic subtest B-DP1: SUCCESS (10.467s)
[78.230231] Starting dynamic subtest: C-DP1
[88.680091] Dynamic subtest C-DP1: SUCCESS (10.468s)
[88.702563] Subtest dpms-off-confusion: SUCCESS (31.776s)
[89.118462] [008/141] (912s left) kms_hdr (static-toggle-suspend)
[89.479275] Starting subtest: static-toggle-suspend
[89.519536] Subtest static-toggle-suspend: SKIP (0.000s)
[89.623336] [009/141] (911s left) gem_exec_flush (basic-uc-prw-default)
[89.960895] Starting subtest: basic-uc-prw-default
[95.590123] Subtest basic-uc-prw-default: SUCCESS (5.479s)
[95.692081] [010/141] (905s left) kms_frontbuffer_tracking (fbcpsr-badstride)
[96.131068] Starting subtest: fbcpsr-badstride
[96.170203] Subtest fbcpsr-badstride: SKIP (0.000s)
[96.270596] [011/141] (905s left) gem_exec_schedule (preempt-self)
[96.715441] Starting subtest: preempt-self
[96.734750] Starting dynamic subtest: rcs0
[96.753752] Dynamic subtest rcs0: SUCCESS (0.040s)
[96.788050] Starting dynamic subtest: bcs0
[96.809435] Dynamic subtest bcs0: SUCCESS (0.038s)
[96.842883] Starting dynamic subtest: vcs0
[96.863544] Dynamic subtest vcs0: SUCCESS (0.041s)
[96.895872] Starting dynamic subtest: vecs0
[96.914882] Dynamic subtest vecs0: SUCCESS (0.038s)
[96.933604] Subtest preempt-self: SUCCESS (0.159s)
[97.113774] [012/141] (904s left) kms_lease (lease_unleased_crtc)
[97.475252] Starting subtest: lease_unleased_crtc
[97.494284] Starting dynamic subtest: pipe-A-DP-1
[97.637855] Dynamic subtest pipe-A-DP-1: SUCCESS (0.166s)
[97.670213] Starting dynamic subtest: pipe-B-DP-1
[97.730618] Dynamic subtest pipe-B-DP-1: SUCCESS (0.096s)
[97.767922] Starting dynamic subtest: pipe-C-DP-1
[97.838821] Dynamic subtest pipe-C-DP-1: SUCCESS (0.094s)
[97.857907] Subtest lease_unleased_crtc: SUCCESS (0.362s)
[98.180518] [013/141] (903s left) gem_exec_fair (basic-flow)
[98.657402] Starting subtest: basic-flow
[98.679147] Starting dynamic subtest: rcs0
[103.460632] Dynamic subtest rcs0: SUCCESS (4.988s)
[103.479672] Subtest basic-flow: SUCCESS (4.989s)
[103.691438] [014/141] (898s left) gem_exec_whisper (basic-contexts-priority)
[104.027803] Starting subtest: basic-contexts-priority
[119.910692] Subtest basic-contexts-priority: SUCCESS (15.914s)
[120.113242] [015/141] (881s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[120.471489] Starting subtest: cursor-vs-flip-legacy
[158.611069] Subtest cursor-vs-flip-legacy: SUCCESS (38.128s)
[158.967043] [016/141] (843s left) kms_chamelium_frames (dp-crc-fast)
[159.442819] Subtest dp-crc-fast: SKIP (0.000s)
[159.762825] [017/141] (842s left) kms_ccs (pipe-b-ccs-on-another-bo-yf_tiled_ccs)
[160.380395] Starting subtest: pipe-B-ccs-on-another-bo-yf_tiled_ccs
[160.401925] Subtest pipe-B-ccs-on-another-bo-yf_tiled_ccs: SUCCESS (0.001s)
[160.856598] [018/141] (841s left) kms_big_fb (linear-16bpp-rotate-0)
[161.244556] Starting subtest: linear-16bpp-rotate-0
[165.755138] Subtest linear-16bpp-rotate-0: SUCCESS (4.526s)
[166.134258] [019/141] (836s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[166.614038] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[166.663348] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[166.937358] [020/141] (835s left) perf_pmu (busy-accuracy-98)
[167.262069] Starting subtest: busy-accuracy-98
[167.286794] Starting dynamic subtest: rcs0
[170.314591] Dynamic subtest rcs0: SUCCESS (3.059s)
[170.351113] Starting dynamic subtest: bcs0
[173.377208] Dynamic subtest bcs0: SUCCESS (3.059s)
[173.417189] Starting dynamic subtest: vcs0
[176.431254] Dynamic subtest vcs0: SUCCESS (3.056s)
[176.471642] Starting dynamic subtest: vecs0
[179.486176] Dynamic subtest vecs0: SUCCESS (3.058s)
[179.509212] Subtest busy-accuracy-98: SUCCESS (12.233s)
[179.633556] [021/141] (823s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-wc)
[180.106416] Starting subtest: fbc-rgb101010-draw-mmap-wc
[182.785499] Subtest fbc-rgb101010-draw-mmap-wc: SUCCESS (2.653s)
[182.879586] [022/141] (820s left) kms_ccs (pipe-b-bad-pixel-format-4_tiled_dg2_rc_ccs)
[183.406056] Starting subtest: pipe-B-bad-pixel-format-4_tiled_dg2_rc_ccs
[183.450391] Subtest pipe-B-bad-pixel-format-4_tiled_dg2_rc_ccs: SKIP (0.000s)
[183.981561] [023/141] (819s left) kms_atomic_transition (plane-primary-toggle-with-vblank-wait)
[184.366877] Starting subtest: plane-primary-toggle-with-vblank-wait
[184.385995] Starting dynamic subtest: pipe-A-DP-1
[184.557910] Dynamic subtest pipe-A-DP-1: SUCCESS (0.201s)
[184.685322] Starting dynamic subtest: pipe-B-DP-1
[185.144645] Dynamic subtest pipe-B-DP-1: SUCCESS (0.483s)
[185.252042] Subtest plane-primary-toggle-with-vblank-wait: SUCCESS (0.887s)
[185.612559] [024/141] (817s left) gem_exec_balancer (parallel-dmabuf-import-out-fence)
[189.959747] Subtest parallel-dmabuf-import-out-fence: SKIP (0.000s)
[190.051306] [025/141] (813s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[190.441092] Starting subtest: yf-tiled-64bpp-rotate-90
[190.479907] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[190.592892] [026/141] (812s left) gem_ctx_persistence (legacy-engines-hang)
[190.878477] Starting subtest: legacy-engines-hang
[190.899729] Starting dynamic subtest: render
[190.941422] Dynamic subtest render: SUCCESS (0.049s)
[190.976674] Starting dynamic subtest: bsd
[190.995569] Dynamic subtest bsd: SUCCESS (0.039s)
[191.029835] Starting dynamic subtest: blt
[191.077289] Dynamic subtest blt: SKIP (0.005s)
[191.113592] Starting dynamic subtest: vebox
[191.134433] Dynamic subtest vebox: SUCCESS (0.045s)
[191.155084] Subtest legacy-engines-hang: SUCCESS (0.142s)
[191.239055] [027/141] (812s left) kms_vblank (pipe-c-query-busy)
[191.604042] Starting subtest: pipe-C-query-busy
[194.612070] Subtest pipe-C-query-busy: SUCCESS (2.994s)
[194.960536] [028/141] (808s left) sysfs_timeslice_duration (idempotent)
[195.257769] Starting subtest: idempotent
[195.279333] Starting dynamic subtest: vecs0
[195.299888] Dynamic subtest vecs0: SUCCESS (0.001s)
[195.334678] Starting dynamic subtest: vcs0
[195.353376] Dynamic subtest vcs0: SUCCESS (0.002s)
[195.387150] Starting dynamic subtest: bcs0
[195.405679] Dynamic subtest bcs0: SUCCESS (0.001s)
[195.440556] Starting dynamic subtest: rcs0
[195.461903] Dynamic subtest rcs0: SUCCESS (0.002s)
[195.482858] Subtest idempotent: SUCCESS (0.010s)
[195.587076] [029/141] (808s left) kms_vblank (pipe-a-ts-continuation-dpms-suspend)
[195.938170] Starting subtest: pipe-A-ts-continuation-dpms-suspend
[214.109813] Subtest pipe-A-ts-continuation-dpms-suspend: SUCCESS (1.740s)
[220.454303] [030/141] (805s left) kms_content_protection (mei_interface)
[220.869437] Starting subtest: mei_interface
[221.583122] Subtest mei_interface: SKIP (0.693s)
[222.024315] [031/141] (804s left) drm_fdinfo (all-busy-idle-check-all)
[222.319008] Starting subtest: all-busy-idle-check-all
[222.925773] Subtest all-busy-idle-check-all: SUCCESS (0.624s)
[223.055280] [032/141] (803s left) kms_psr (suspend)
[223.367728] Subtest suspend: SKIP (0.000s)
[223.479260] [033/141] (803s left) vgem_basic (mmap)
[223.889186] Starting subtest: mmap
[223.908163] Subtest mmap: SUCCESS (0.027s)
[224.020460] [034/141] (802s left) sw_sync (alloc_timeline)
[224.291364] Starting subtest: alloc_timeline
[224.319063] Subtest alloc_timeline: SUCCESS (0.000s)
[224.431001] [035/141] (802s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-mmap-gtt)
[224.868842] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-mmap-gtt
[224.909845] Subtest fbcpsr-1p-offscren-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[225.014585] [036/141] (801s left) gen9_exec_parse (batch-zero-length)
[225.318526] Starting subtest: batch-zero-length
[225.337701] Subtest batch-zero-length: SUCCESS (0.003s)
[225.583876] [037/141] (801s left) gem_create (create-ext-cpu-access-sanity-check)
[225.993872] Starting subtest: create-ext-cpu-access-sanity-check
[226.042823] Subtest create-ext-cpu-access-sanity-check: SKIP (0.001s)
[226.152585] [038/141] (800s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-render)
[226.587758] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-render
[228.650492] Subtest fbc-1p-primscrn-cur-indfb-draw-render: SUCCESS (2.139s)
[228.820872] [039/141] (798s left) perf (gen8-unprivileged-single-ctx-counters)
[229.272114] Starting subtest: gen8-unprivileged-single-ctx-counters
[229.498053] Subtest gen8-unprivileged-single-ctx-counters: SUCCESS (0.227s)
[229.612094] [040/141] (797s left) gem_flink_race (flink_name)
[229.885421] Starting subtest: flink_name
[235.442025] Subtest flink_name: SUCCESS (5.375s)
[235.538974] [041/141] (791s left) kms_ccs (pipe-d-crc-primary-rotation-180-y_tiled_gen12_mc_ccs)
[236.297210] Subtest pipe-D-crc-primary-rotation-180-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[236.636656] [042/141] (790s left) gem_ctx_create (basic-files)
[236.932445] Starting subtest: basic-files
[238.967465] Subtest basic-files: SUCCESS (2.035s)
[239.230874] [043/141] (788s left) gem_render_copy (x-tiled-to-vebox-yf-tiled)
[239.529760] Starting subtest: x-tiled-to-vebox-yf-tiled
[239.568697] Subtest x-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[239.746944] [044/141] (787s left) kms_chamelium_hpd (hdmi-hpd)
[240.222441] Subtest hdmi-hpd: SKIP (0.000s)
[240.552874] [045/141] (787s left) kms_vblank (pipe-a-wait-forked-hang)
[240.908513] Starting subtest: pipe-A-wait-forked-hang
[247.588600] Subtest pipe-A-wait-forked-hang: SUCCESS (6.550s)
[247.848909] [046/141] (780s left) gem_create (create-invalid-size)
[248.141844] Starting subtest: create-invalid-size
[248.160589] Subtest create-invalid-size: SUCCESS (0.000s)
[248.367094] [047/141] (779s left) syncobj_wait (multi-wait-all-submitted-signaled)
[248.663169] Starting subtest: multi-wait-all-submitted-signaled
[248.745395] Subtest multi-wait-all-submitted-signaled: SUCCESS (0.109s)
[248.857861] [048/141] (779s left) kms_big_fb (y-tiled-32bpp-rotate-0)
[249.279805] Starting subtest: y-tiled-32bpp-rotate-0
[254.143490] Subtest y-tiled-32bpp-rotate-0: SUCCESS (4.908s)
[254.688188] [049/141] (773s left) kms_cursor_legacy (cursora-vs-flipa-toggle)
[255.040542] Starting subtest: cursorA-vs-flipA-toggle
[266.111055] Subtest cursorA-vs-flipA-toggle: SUCCESS (11.048s)
[266.466662] [050/141] (761s left) gem_eio (in-flight-internal-1us)
[266.810545] Starting subtest: in-flight-internal-1us
[266.872448] Subtest in-flight-internal-1us: SUCCESS (0.105s)
[267.109523] [051/141] (761s left) vgem_basic (second-client)
[267.403101] Starting subtest: second-client
[267.441023] Subtest second-client: SUCCESS (0.034s)
[267.562943] [052/141] (760s left) kms_ccs (pipe-b-crc-primary-rotation-180-4_tiled_dg2_rc_ccs)
[268.117537] Starting subtest: pipe-B-crc-primary-rotation-180-4_tiled_dg2_rc_ccs
[268.155133] Subtest pipe-B-crc-primary-rotation-180-4_tiled_dg2_rc_ccs: SKIP (0.001s)
[268.627167] [053/141] (759s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-render)
[269.043078] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-render
[269.085912] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[269.198118] [054/141] (759s left) kms_vblank (pipe-d-query-idle-hang)
[269.572451] Subtest pipe-D-query-idle-hang: SKIP (0.000s)
[269.765639] [055/141] (759s left) syncobj_wait (multi-wait-submitted-signaled)
[270.082161] Starting subtest: multi-wait-submitted-signaled
[270.107946] Subtest multi-wait-submitted-signaled: SUCCESS (0.001s)
[270.242603] [056/141] (758s left) i915_pm_freq_mult (media-freq)
[270.569076] Starting subtest: media-freq
[270.593248] Starting dynamic subtest: gt0
[270.647557] Dynamic subtest gt0: SKIP (0.000s)
[270.696798] Subtest media-freq: SKIP (0.002s)
[270.837266] [057/141] (758s left) i915_pm_rps (thresholds)
[271.306814] Starting subtest: thresholds
[271.331908] Starting dynamic subtest: gt0
[271.379917] Dynamic subtest gt0: SKIP (0.004s)
[271.434461] Subtest thresholds: SKIP (0.007s)
[271.597548] [058/141] (757s left) kms_atomic_transition (modeset-transition-nonblocking-fencing)
[271.992311] Starting subtest: modeset-transition-nonblocking-fencing
[272.017237] Starting dynamic subtest: 1x-outputs
[276.888215] Dynamic subtest 1x-outputs: SUCCESS (4.927s)
[276.999000] Subtest modeset-transition-nonblocking-fencing: SUCCESS (5.035s)
[277.390322] [059/141] (751s left) gem_exec_fence (syncobj-invalid-flags)
[278.139049] Starting subtest: syncobj-invalid-flags
[278.157853] Subtest syncobj-invalid-flags: SUCCESS (0.001s)
[278.539587] [060/141] (750s left) perf_pmu (multi-client)
[278.831113] Starting subtest: multi-client
[278.852099] Starting dynamic subtest: rcs0
[279.832796] Dynamic subtest rcs0: SUCCESS (1.031s)
[279.873814] Starting dynamic subtest: bcs0
[280.869887] Dynamic subtest bcs0: SUCCESS (1.032s)
[280.911830] Starting dynamic subtest: vcs0
[281.896426] Dynamic subtest vcs0: SUCCESS (1.025s)
[281.935690] Starting dynamic subtest: vecs0
[282.921585] Dynamic subtest vecs0: SUCCESS (1.025s)
[282.940623] Subtest multi-client: SUCCESS (4.122s)
[283.076758] [061/141] (746s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-pwrite)
[283.495768] Starting subtest: psr-1p-offscren-pri-shrfb-draw-pwrite
[283.537753] Subtest psr-1p-offscren-pri-shrfb-draw-pwrite: SKIP (0.000s)
[283.652373] [062/141] (746s left) v3d/v3d_perfmon (create-two-perfmon)
[283.988189] Subtest create-two-perfmon: SKIP (0.000s)
[284.102791] [063/141] (745s left) gem_exec_params (invalid-batch-start-offset)
[284.397410] Starting subtest: invalid-batch-start-offset
[284.418944] Subtest invalid-batch-start-offset: SUCCESS (0.008s)
[284.536659] [064/141] (745s left) v3d/v3d_submit_cl (bad-multisync-in-sync)
[284.875495] Subtest bad-multisync-in-sync: SKIP (0.000s)
[284.987812] [065/141] (744s left) dumb_buffer (map-invalid-size)
[285.282468] Starting subtest: map-invalid-size
[285.304056] Subtest map-invalid-size: SUCCESS (0.000s)
[285.418087] [066/141] (744s left) gem_mmap_gtt (basic-small-copy)
[285.720965] Starting subtest: basic-small-copy
[289.820363] Subtest basic-small-copy: SUCCESS (4.122s)
[289.951634] [067/141] (740s left) kms_vblank (pipe-d-wait-busy)
[290.515223] Subtest pipe-D-wait-busy: SKIP (0.000s)
[290.712285] [068/141] (739s left) kms_vblank (pipe-c-ts-continuation-modeset-hang)
[291.069635] Starting subtest: pipe-C-ts-continuation-modeset-hang
[298.415488] Subtest pipe-C-ts-continuation-modeset-hang: SUCCESS (7.332s)
[298.801031] [069/141] (731s left) kms_rmfb (rmfb-ioctl)
[299.169304] Starting subtest: rmfb-ioctl
[299.189602] Starting dynamic subtest: pipe-A-DP-1
[299.282571] Dynamic subtest pipe-A-DP-1: SUCCESS (0.110s)
[299.357330] Starting dynamic subtest: pipe-B-DP-1
[299.707162] Dynamic subtest pipe-B-DP-1: SUCCESS (0.427s)
[299.748913] Starting dynamic subtest: pipe-C-DP-1
[300.133643] Dynamic subtest pipe-C-DP-1: SUCCESS (0.412s)
[300.156056] Subtest rmfb-ioctl: SUCCESS (0.961s)
[300.552329] [070/141] (729s left) kms_cursor_crc (cursor-rapid-movement-128x42)
[300.998609] Starting subtest: cursor-rapid-movement-128x42
[301.024866] Starting dynamic subtest: pipe-A-DP-1
[301.797070] Dynamic subtest pipe-A-DP-1: SUCCESS (0.787s)
[301.874174] Starting dynamic subtest: pipe-C-DP-1
[302.701642] Dynamic subtest pipe-C-DP-1: SUCCESS (0.841s)
[302.723803] Subtest cursor-rapid-movement-128x42: SUCCESS (1.707s)
[303.096390] [071/141] (727s left) kms_psr_stress_test (invalidate-primary-flip-overlay)
[303.409717] Subtest invalidate-primary-flip-overlay: SKIP (0.000s)
[303.531839] [072/141] (727s left) gem_render_copy (y-tiled-ccs-to-y-tiled-ccs)
[303.829299] Starting subtest: y-tiled-ccs-to-y-tiled-ccs
[303.849736] Starting dynamic subtest: smem
[304.131288] Dynamic subtest smem: SUCCESS (0.316s)
[304.150307] Subtest y-tiled-ccs-to-y-tiled-ccs: SUCCESS (0.319s)
[304.393131] [073/141] (726s left) gem_exec_reloc (basic-cpu-gtt-noreloc)
[304.695016] Starting subtest: basic-cpu-gtt-noreloc
[304.715134] Subtest basic-cpu-gtt-noreloc: SUCCESS (0.016s)
[304.912349] [074/141] (726s left) kms_chamelium_edid (dp-edid-stress-resolution-4k)
[305.400524] Subtest dp-edid-stress-resolution-4k: SKIP (0.000s)
[305.733721] [075/141] (725s left) kms_ccs (pipe-c-random-ccs-data-4_tiled_mtl_mc_ccs)
[306.438883] Starting subtest: pipe-C-random-ccs-data-4_tiled_mtl_mc_ccs
[306.501093] Subtest pipe-C-random-ccs-data-4_tiled_mtl_mc_ccs: SKIP (0.000s)
[306.837548] [076/141] (724s left) api_intel_allocator (alloc-simple)
[307.136716] Starting subtest: alloc-simple
[307.155768] Subtest alloc-simple: SUCCESS (0.004s)
[307.268913] [077/141] (724s left) kms_vblank (pipe-c-wait-idle)
[307.623836] Starting subtest: pipe-C-wait-idle
[310.404803] Subtest pipe-C-wait-idle: SUCCESS (2.779s)
[310.787006] [078/141] (720s left) kms_ccs (pipe-a-ccs-on-another-bo-y_tiled_ccs)
[311.289730] Starting subtest: pipe-A-ccs-on-another-bo-y_tiled_ccs
[311.309332] Subtest pipe-A-ccs-on-another-bo-y_tiled_ccs: SUCCESS (0.001s)
[311.880268] [079/141] (719s left) kms_pipe_crc_basic (read-crc)
[312.272633] Starting subtest: read-crc
[312.294645] Starting dynamic subtest: pipe-A-DP-1
[312.797250] Dynamic subtest pipe-A-DP-1: SUCCESS (0.531s)
[312.850561] Starting dynamic subtest: pipe-B-DP-1
[313.640062] Dynamic subtest pipe-B-DP-1: SUCCESS (0.824s)
[313.687716] Starting dynamic subtest: pipe-C-DP-1
[314.601449] Dynamic subtest pipe-C-DP-1: SUCCESS (0.965s)
[314.624014] Subtest read-crc: SUCCESS (2.335s)
[314.943460] [080/141] (716s left) kms_cursor_crc (cursor-onscreen-128x42)
[315.335055] Starting subtest: cursor-onscreen-128x42
[315.356709] Starting dynamic subtest: pipe-A-DP-1
[317.707554] Dynamic subtest pipe-A-DP-1: SUCCESS (2.352s)
[317.778334] Starting dynamic subtest: pipe-C-DP-1
[320.456134] Dynamic subtest pipe-C-DP-1: SUCCESS (2.687s)
[320.477134] Subtest cursor-onscreen-128x42: SUCCESS (5.120s)
[320.835489] [081/141] (710s left) gem_exec_reloc (basic-gtt-noreloc)
[321.155253] Starting subtest: basic-gtt-noreloc
[321.178889] Subtest basic-gtt-noreloc: SUCCESS (0.011s)
[321.349734] [082/141] (710s left) kms_ccs (pipe-d-crc-sprite-planes-basic-4_tiled_mtl_rc_ccs)
[322.141259] Subtest pipe-D-crc-sprite-planes-basic-4_tiled_mtl_rc_ccs: SKIP (0.000s)
[322.491807] [083/141] (709s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[322.923306] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[323.006291] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.004s)
[323.118274] [084/141] (708s left) i915_pm_rpm (pm-caching)
[324.969810] Starting subtest: pm-caching
[326.591119] Subtest pm-caching: SUCCESS (1.623s)
[327.098795] [085/141] (705s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-blt)
[327.559411] Starting subtest: psr-2p-primscrn-cur-indfb-draw-blt
[327.597049] Subtest psr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[327.715489] [086/141] (704s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[328.109531] Starting subtest: x-tiled-64bpp-rotate-90
[328.193473] Subtest x-tiled-64bpp-rotate-90: SKIP (0.075s)
[328.347627] [087/141] (704s left) gem_close (many-handles-one-vma)
[328.647480] Starting subtest: many-handles-one-vma
[328.709475] Subtest many-handles-one-vma: SUCCESS (0.087s)
[328.884159] [088/141] (703s left) kms_ccs (pipe-a-crc-sprite-planes-basic-4_tiled_mtl_mc_ccs)
[329.407104] Starting subtest: pipe-A-crc-sprite-planes-basic-4_tiled_mtl_mc_ccs
[329.446800] Subtest pipe-A-crc-sprite-planes-basic-4_tiled_mtl_mc_ccs: SKIP (0.005s)
[329.983901] [089/141] (702s left) syncobj_timeline (invalid-multi-wait-unsubmitted-signaled)
[330.278451] Starting subtest: invalid-multi-wait-unsubmitted-signaled
[330.299546] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.002s)
[330.411790] [090/141] (702s left) syncobj_timeline (multi-wait-for-submit-signaled)
[330.702957] Starting subtest: multi-wait-for-submit-signaled
[330.721989] Subtest multi-wait-for-submit-signaled: SUCCESS (0.003s)
[330.839014] [091/141] (702s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-downscaling)
[331.208272] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-downscaling
[331.228816] Starting dynamic subtest: pipe-A-valid-mode
[331.933119] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.718s)
[331.952532] Subtest flip-64bpp-ytile-to-16bpp-ytile-downscaling: SUCCESS (0.722s)
[332.312095] [092/141] (700s left) i915_query (query-garbage)
[332.613209] Starting subtest: query-garbage
[332.634304] Subtest query-garbage: SUCCESS (0.000s)
[332.746072] [093/141] (700s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-20x20)
[333.093754] Starting subtest: planes-downscale-factor-0-75-upscale-20x20
[333.114345] Starting dynamic subtest: pipe-A-DP-1
[333.189558] Dynamic subtest pipe-A-DP-1: SUCCESS (0.089s)
[333.231904] Starting dynamic subtest: pipe-B-DP-1
[333.594785] Dynamic subtest pipe-B-DP-1: SUCCESS (0.379s)
[333.638461] Starting dynamic subtest: pipe-C-DP-1
[333.705997] Dynamic subtest pipe-C-DP-1: SKIP (0.091s)
[333.725230] Subtest planes-downscale-factor-0-75-upscale-20x20: SUCCESS (0.575s)
[334.139541] [094/141] (699s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc)
[334.564511] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc
[334.605001] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[334.720138] [095/141] (698s left) gem_huc_copy (huc-copy)
[335.008268] Starting subtest: huc-copy
[335.047773] Subtest huc-copy: SKIP (0.000s)
[335.167548] [096/141] (698s left) kms_ccs (pipe-d-ccs-on-another-bo-4_tiled_mtl_mc_ccs)
[335.914725] Subtest pipe-D-ccs-on-another-bo-4_tiled_mtl_mc_ccs: SKIP (0.000s)
[336.266536] [097/141] (697s left) kms_prime (basic-crc-vgem)
[338.470492] Starting subtest: basic-crc-vgem
[338.491450] Starting dynamic subtest: second-to-first
[339.639598] Dynamic subtest second-to-first: SUCCESS (1.204s)
[339.661308] Subtest basic-crc-vgem: SUCCESS (1.207s)
[339.791140] [098/141] (693s left) kms_addfb_basic (unused-pitches)
[340.088174] Starting subtest: unused-pitches
[340.108222] Subtest unused-pitches: SUCCESS (0.000s)
[340.288873] [099/141] (693s left) kms_flip (blocking-wf_vblank)
[340.703364] Starting subtest: blocking-wf_vblank
[340.727478] Starting dynamic subtest: A-DP1
[351.689618] Dynamic subtest A-DP1: SUCCESS (10.986s)
[351.736941] Starting dynamic subtest: B-DP1
[362.410438] Dynamic subtest B-DP1: SUCCESS (10.746s)
[362.450766] Starting dynamic subtest: C-DP1
[373.159057] Dynamic subtest C-DP1: SUCCESS (10.744s)
[373.178079] Subtest blocking-wf_vblank: SUCCESS (32.491s)
[373.612842] [100/141] (660s left) kms_addfb_basic (master-rmfb)
[373.909303] Starting subtest: master-rmfb
[373.928531] Subtest master-rmfb: SUCCESS (0.001s)
[374.128409] [101/141] (659s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180)
[374.522054] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180
[375.838719] Subtest y-tiled-max-hw-stride-64bpp-rotate-180: SUCCESS (1.335s)
[376.028165] [102/141] (658s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[376.467414] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[376.507775] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[376.623581] [103/141] (657s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-fullscreen)
[377.040308] Starting subtest: fbc-2p-primscrn-spr-indfb-fullscreen
[377.082601] Subtest fbc-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[377.204014] [104/141] (657s left) gem_exec_balancer (bonded-false-hang)
[381.560262] Starting subtest: bonded-false-hang
[385.192438] Subtest bonded-false-hang: SUCCESS (3.649s)
[385.285657] [105/141] (649s left) kms_ccs (pipe-c-bad-aux-stride-yf_tiled_ccs)
[385.999321] Starting subtest: pipe-C-bad-aux-stride-yf_tiled_ccs
[386.039760] Subtest pipe-C-bad-aux-stride-yf_tiled_ccs: SKIP (0.000s)
[386.382221] [106/141] (648s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-blt)
[386.830422] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-blt
[386.868074] Subtest psr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[386.983375] [107/141] (647s left) kms_psr2_sf (primary-plane-update-sf-dmg-area)
[387.297855] Subtest primary-plane-update-sf-dmg-area: SKIP (0.000s)
[387.414029] [108/141] (647s left) gem_ctx_param (invalid-get-no-zeromap)
[387.711963] Starting subtest: invalid-get-no-zeromap
[387.732487] Subtest invalid-get-no-zeromap: SUCCESS (0.000s)
[387.842041] [109/141] (647s left) kms_ccs (pipe-a-ccs-on-another-bo-4_tiled_mtl_rc_ccs)
[388.337635] Starting subtest: pipe-A-ccs-on-another-bo-4_tiled_mtl_rc_ccs
[388.378759] Subtest pipe-A-ccs-on-another-bo-4_tiled_mtl_rc_ccs: SKIP (0.000s)
[388.934836] [110/141] (646s left) gem_watchdog (default-virtual)
[389.390236] Starting subtest: default-virtual
[390.239437] Subtest default-virtual: SUCCESS (1.030s)
[390.379163] [111/141] (644s left) syncobj_timeline (invalid-multi-wait-all-available-unsubmitted-submitted-sign
[390.646885] Starting subtest: invalid-multi-wait-all-available-unsubmitted-submitted-signaled
[390.668123] Subtest invalid-multi-wait-all-available-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[390.762112] [112/141] (644s left) gem_pxp (dmabuf-shared-protected-dst-is-context-refcounted)
[393.762635] Subtest dmabuf-shared-protected-dst-is-context-refcounted: SKIP (0.000s)
[393.900395] [113/141] (641s left) kms_ccs (pipe-c-crc-sprite-planes-basic-4_tiled_mtl_rc_ccs_cc)
[394.613884] Starting subtest: pipe-C-crc-sprite-planes-basic-4_tiled_mtl_rc_ccs_cc
[394.651110] Subtest pipe-C-crc-sprite-planes-basic-4_tiled_mtl_rc_ccs_cc: SKIP (0.004s)
[394.981201] [114/141] (640s left) gem_mmap_offset (open-flood)
[395.279587] Starting subtest: open-flood
[416.823733] Subtest open-flood: SUCCESS (21.561s)
[416.938645] [115/141] (618s left) dmabuf_sync_file (export-wait-after-attach)
[417.240963] Starting subtest: export-wait-after-attach
[417.259770] Subtest export-wait-after-attach: SUCCESS (0.002s)
[417.351898] [116/141] (618s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-render)
[417.792532] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-render
[417.829565] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[417.946352] [117/141] (617s left) prime_vgem (basic-read)
[418.240086] Starting subtest: basic-read
[418.283600] Subtest basic-read: SUCCESS (0.052s)
[418.419217] [118/141] (617s left) gem_partial_pwrite_pread (reads-display)
[418.716803] Starting subtest: reads-display
[425.097464] Subtest reads-display: SUCCESS (6.394s)
[425.236260] [119/141] (610s left) v3d/v3d_submit_csd (valid-multisync-submission)
[425.590886] Subtest valid-multisync-submission: SKIP (0.000s)
[425.689101] [120/141] (610s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-render)
[426.105823] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-render
[426.148265] Subtest fbc-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[426.266780] [121/141] (609s left) vc4/vc4_perfmon (create-perfmon-0)
[426.609244] Subtest create-perfmon-0: SKIP (0.000s)
[426.700660] [122/141] (609s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-blt)
[427.102633] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-blt
[427.142750] Subtest psr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[427.257468] [123/141] (609s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling)
[427.593938] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling
[427.616503] Starting dynamic subtest: pipe-A-valid-mode
[427.728099] Dynamic subtest pipe-A-valid-mode: SKIP (0.106s)
[427.766852] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling: SKIP (0.110s)
[428.105972] [124/141] (608s left) syncobj_timeline (single-wait-signaled)
[428.393421] Starting subtest: single-wait-signaled
[428.414382] Subtest single-wait-signaled: SUCCESS (0.001s)
[428.525024] [125/141] (607s left) kms_setmode (invalid-clone-exclusive-crtc)
[428.823410] Starting subtest: invalid-clone-exclusive-crtc
[428.881273] Subtest invalid-clone-exclusive-crtc: SKIP (0.000s)
[429.000819] [126/141] (607s left) kms_draw_crc (draw-method-pwrite)
[429.359347] Starting subtest: draw-method-pwrite
[429.378020] Starting dynamic subtest: xrgb8888-untiled
[429.669667] Dynamic subtest xrgb8888-untiled: SUCCESS (0.306s)
[429.710889] Starting dynamic subtest: rgb565-untiled
[429.932032] Dynamic subtest rgb565-untiled: SUCCESS (0.264s)
[429.973426] Starting dynamic subtest: xrgb2101010-untiled
[430.214765] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.281s)
[430.256146] Starting dynamic subtest: xrgb8888-xtiled
[430.532706] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.314s)
[430.573483] Starting dynamic subtest: rgb565-xtiled
[430.854304] Dynamic subtest rgb565-xtiled: SUCCESS (0.314s)
[430.895193] Starting dynamic subtest: xrgb2101010-xtiled
[431.172547] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.315s)
[431.214833] Starting dynamic subtest: xrgb8888-ytiled
[431.484522] Dynamic subtest xrgb8888-ytiled: SUCCESS (0.314s)
[431.527860] Starting dynamic subtest: rgb565-ytiled
[431.765767] Dynamic subtest rgb565-ytiled: SUCCESS (0.281s)
[431.806905] Starting dynamic subtest: xrgb2101010-ytiled
[432.084989] Dynamic subtest xrgb2101010-ytiled: SUCCESS (0.315s)
[432.103914] Subtest draw-method-pwrite: SUCCESS (2.725s)
[432.221966] [127/141] (604s left) gem_eio (hibernate)
[432.556606] Starting subtest: hibernate
[448.323120] Subtest hibernate: SUCCESS (15.668s)
[453.056227] [128/141] (588s left) kms_flip (flip-vs-panning-vs-hang)
[453.438071] Starting subtest: flip-vs-panning-vs-hang
[453.456718] Starting dynamic subtest: A-DP1
[456.213817] Dynamic subtest A-DP1: SUCCESS (2.794s)
[456.255759] Starting dynamic subtest: C-DP1
[458.968514] Dynamic subtest C-DP1: SUCCESS (2.741s)
[458.987783] Subtest flip-vs-panning-vs-hang: SUCCESS (5.544s)
[459.450279] [129/141] (582s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions-varying-s
[459.821298] Starting subtest: short-busy-flip-before-cursor-atomic-transitions-varying-size
[459.864095] Subtest short-busy-flip-before-cursor-atomic-transitions-varying-size: SKIP (0.000s)
[459.978443] [130/141] (581s left) prime_busy (before-wait)
[460.277961] Starting subtest: before-wait
[460.296930] Starting dynamic subtest: rcs0
[460.315787] Dynamic subtest rcs0: SUCCESS (0.020s)
[460.357131] Starting dynamic subtest: bcs0
[460.378313] Dynamic subtest bcs0: SUCCESS (0.019s)
[460.422596] Starting dynamic subtest: vcs0
[460.443638] Dynamic subtest vcs0: SUCCESS (0.016s)
[460.487287] Starting dynamic subtest: vecs0
[460.506434] Dynamic subtest vecs0: SUCCESS (0.015s)
[460.525505] Subtest before-wait: SUCCESS (0.210s)
[460.620663] [131/141] (581s left) api_intel_bb (blit-reloc-keep-cache)
[460.916210] Starting subtest: blit-reloc-keep-cache
[460.935136] Subtest blit-reloc-keep-cache: SUCCESS (0.020s)
[461.052946] [132/141] (580s left) gem_mmap_gtt (bad-object)
[461.364537] Starting subtest: bad-object
[461.388329] Subtest bad-object: SUCCESS (0.000s)
[461.504901] [133/141] (580s left) gem_ctx_param (set-priority-range)
[461.815383] Starting subtest: set-priority-range
[461.864714] Subtest set-priority-range: SUCCESS (0.066s)
[461.977295] [134/141] (580s left) syncobj_wait (multi-wait-all-for-submit-submitted-signaled)
[462.275856] Starting subtest: multi-wait-all-for-submit-submitted-signaled
[462.353638] Subtest multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.101s)
[462.465416] [135/141] (579s left) gem_exec_schedule (semaphore-resolve)
[462.899821] Starting subtest: semaphore-resolve
[462.921157] Subtest semaphore-resolve: SUCCESS (0.050s)
[463.180094] [136/141] (579s left) kms_properties (crtc-properties-legacy)
[463.546924] Starting subtest: crtc-properties-legacy
[464.869750] Subtest crtc-properties-legacy: SUCCESS (1.320s)
[465.237020] [137/141] (577s left) kms_ccs (pipe-c-ccs-on-another-bo-y_tiled_gen12_rc_ccs_cc)
[465.926397] Starting subtest: pipe-C-ccs-on-another-bo-y_tiled_gen12_rc_ccs_cc
[465.965978] Subtest pipe-C-ccs-on-another-bo-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[466.312523] [138/141] (576s left) gem_ctx_shared (q-smoketest)
[466.607366] Starting subtest: Q-smoketest
[466.629494] Starting dynamic subtest: rcs0
[472.391210] Dynamic subtest rcs0: SUCCESS (5.480s)
[472.447810] Starting dynamic subtest: bcs0
[477.831321] Dynamic subtest bcs0: SUCCESS (5.474s)
[477.884399] Starting dynamic subtest: vcs0
[483.301440] Dynamic subtest vcs0: SUCCESS (5.469s)
[483.351795] Starting dynamic subtest: vecs0
[488.778255] Dynamic subtest vecs0: SUCCESS (5.474s)
[488.803080] Subtest Q-smoketest: SUCCESS (21.908s)
[488.908315] [139/141] (553s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-pgflip-blt)
[489.380824] Starting subtest: psr-1p-primscrn-shrfb-pgflip-blt
[489.426842] Subtest psr-1p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[489.569193] [140/141] (553s left) kms_ccs (pipe-c-bad-rotation-90-4_tiled_mtl_rc_ccs)
[490.221841] Starting subtest: pipe-C-bad-rotation-90-4_tiled_mtl_rc_ccs
[490.271116] Subtest pipe-C-bad-rotation-90-4_tiled_mtl_rc_ccs: SKIP (0.000s)
[490.654143] [141/141] (552s left) gem_spin_batch (legacy)
[490.952637] Starting subtest: legacy
[490.974423] Starting dynamic subtest: default
[494.464946] Dynamic subtest default: SUCCESS (3.324s)
[494.510980] Starting dynamic subtest: render
[497.794617] Dynamic subtest render: SUCCESS (3.323s)
[497.837311] Starting dynamic subtest: bsd
[501.115096] Dynamic subtest bsd: SUCCESS (3.322s)
[501.158586] Starting dynamic subtest: bsd1
[506.131652] Dynamic subtest bsd1: FAIL (3.616s)
[506.176210] Starting dynamic subtest: blt
[507.887010] Dynamic subtest blt: SUCCESS (3.323s)
[507.927646] Starting dynamic subtest: vebox
[511.397556] Dynamic subtest vebox: SUCCESS (3.321s)
[511.418553] Subtest legacy: FAIL (20.248s)
[511.491918] Closing watchdogs
Done.