[63.505403] Initializing watchdogs
[63.505508]   /dev/watchdog0
[63.520342] [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]
[63.524818] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[63.527211] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[63.530576] [001/133] (960s left) kms_cursor_legacy (cursorb-vs-flipa-atomic)
[63.669268] Starting subtest: cursorB-vs-flipA-atomic
[63.673727] Subtest cursorB-vs-flipA-atomic: SKIP (0.001s)
[63.819998] [002/133] (960s left) kms_frontbuffer_tracking (fbcpsr-2p-shrfb-fliptrack-mmap-gtt)
[63.919097] Starting subtest: fbcpsr-2p-shrfb-fliptrack-mmap-gtt
[63.923286] Subtest fbcpsr-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[64.035480] [003/133] (960s left) perf (mi-rpc)
[64.166364] Starting subtest: mi-rpc
[64.170748] Subtest mi-rpc: SKIP (0.000s)
[64.206215] [004/133] (959s left) gem_tiled_pread_basic (basic)
[64.264435] Subtest basic: SKIP (0.000s)
[64.323848] [005/133] (959s left) kms_chamelium_hpd (dp-hpd-enable-disable-mode)
[64.722066] Subtest dp-hpd-enable-disable-mode: SKIP (0.000s)
[65.496457] [006/133] (958s left) gem_ctx_param (invalid-ctx-set)
[65.552572] Starting subtest: invalid-ctx-set
[65.555065] Subtest invalid-ctx-set: SUCCESS (0.002s)
[65.664960] [007/133] (958s left) kms_pipe_stress (stress-xrgb8888-xtiled)
[65.769486] Starting subtest: stress-xrgb8888-xtiled
[71.833257] Subtest stress-xrgb8888-xtiled: SUCCESS (6.061s)
[71.882130] [008/133] (952s left) gem_mmap_gtt (basic-write-gtt)
[71.947987] Subtest basic-write-gtt: SKIP (0.000s)
[72.006115] [009/133] (952s left) kms_ccs (random-ccs-data-y-tiled-gen12-mc-ccs)
[72.096496] Starting subtest: random-ccs-data-y-tiled-gen12-mc-ccs
[72.098488] Starting dynamic subtest: pipe-A-eDP-1
[72.118935] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[72.131553] Starting dynamic subtest: pipe-B-eDP-1
[72.151387] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[72.159765] Starting dynamic subtest: pipe-C-eDP-1
[72.179044] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[72.185540] Starting dynamic subtest: pipe-D-eDP-1
[72.206600] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[72.210038] Subtest random-ccs-data-y-tiled-gen12-mc-ccs: SKIP (0.003s)
[74.290680] [010/133] (950s left) kms_psr_stress_test (invalidate-primary-flip-overlay)
[74.376166] Starting subtest: invalidate-primary-flip-overlay
[85.074510] Subtest invalidate-primary-flip-overlay: SUCCESS (10.696s)
[85.198589] [011/133] (939s left) syncobj_timeline (etime-single-wait-available-unsubmitted)
[85.259539] Starting subtest: etime-single-wait-available-unsubmitted
[85.370205] Subtest etime-single-wait-available-unsubmitted: SUCCESS (0.108s)
[85.470988] [012/133] (938s left) api_intel_allocator (gem-pool)
[85.524976] Starting subtest: gem-pool
[85.530755] Subtest gem-pool: SUCCESS (0.008s)
[85.641895] [013/133] (938s left) kms_frontbuffer_tracking (fbcpsr-2p-pri-indfb-multidraw)
[85.743539] Starting subtest: fbcpsr-2p-pri-indfb-multidraw
[85.748106] Subtest fbcpsr-2p-pri-indfb-multidraw: SKIP (0.000s)
[85.836606] [014/133] (938s left) gem_render_copy (x-tiled)
[85.890448] Starting subtest: x-tiled
[85.893045] Starting dynamic subtest: smem
[85.931077] Dynamic subtest smem: SUCCESS (0.041s)
[85.933935] Subtest x-tiled: SUCCESS (0.041s)
[86.167986] [015/133] (938s left) kms_big_fb (yf-tiled-8bpp-rotate-270)
[86.276281] Starting subtest: yf-tiled-8bpp-rotate-270
[86.280309] Subtest yf-tiled-8bpp-rotate-270: SKIP (0.000s)
[86.385763] [016/133] (938s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-blt)
[86.479109] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-blt
[86.483257] Subtest psr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[86.612123] [017/133] (938s left) kms_flip (basic-flip-vs-wf_vblank)
[86.699208] Starting subtest: basic-flip-vs-wf_vblank
[86.701489] Starting dynamic subtest: A-eDP1
[88.634671] Dynamic subtest A-eDP1: SUCCESS (1.937s)
[88.645518] Starting dynamic subtest: B-eDP1
[90.562646] Dynamic subtest B-eDP1: SUCCESS (1.927s)
[90.572814] Starting dynamic subtest: C-eDP1
[92.488306] Dynamic subtest C-eDP1: SUCCESS (1.924s)
[92.496343] Starting dynamic subtest: D-eDP1
[94.424842] Dynamic subtest D-eDP1: SUCCESS (1.935s)
[94.427277] Subtest basic-flip-vs-wf_vblank: SUCCESS (7.731s)
[95.653396] [018/133] (929s left) gem_bad_reloc (negative-reloc)
[95.716293] Subtest negative-reloc: SKIP (0.000s)
[95.781547] [019/133] (928s left) gem_exec_fence (syncobj-stationary-timeline-chain-engines)
[96.358782] Starting subtest: syncobj-stationary-timeline-chain-engines
[96.456850] Subtest syncobj-stationary-timeline-chain-engines: SUCCESS (0.099s)
[97.968876] [FACT gem_exec_fence (syncobj-stationary-timeline-chain-engines)] new: kernel.kmod_is_loaded.vgem: true
[97.972892] [020/133] (926s left) kms_getfb (getfb-handle-closed)
[98.061064] Starting subtest: getfb-handle-closed
[98.063415] Subtest getfb-handle-closed: SUCCESS (0.000s)
[98.150902] [021/133] (926s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-onoff)
[98.242396] Starting subtest: fbc-2p-primscrn-spr-indfb-onoff
[98.246445] Subtest fbc-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[98.337935] [022/133] (926s left) kms_flip (flip-vs-absolute-wf_vblank-interruptible)
[98.426748] Starting subtest: flip-vs-absolute-wf_vblank-interruptible
[98.429950] Starting dynamic subtest: A-eDP1
[107.302125] Dynamic subtest A-eDP1: SUCCESS (8.873s)
[107.309512] Starting dynamic subtest: B-eDP1
[116.232324] Dynamic subtest B-eDP1: SUCCESS (8.928s)
[116.239642] Starting dynamic subtest: C-eDP1
[125.145633] Dynamic subtest C-eDP1: SUCCESS (8.911s)
[125.154067] Starting dynamic subtest: D-eDP1
[133.986643] Dynamic subtest D-eDP1: SUCCESS (8.840s)
[133.988912] Subtest flip-vs-absolute-wf_vblank-interruptible: SUCCESS (35.560s)
[135.211474] [023/133] (889s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-pwrite)
[135.305888] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-pwrite
[140.611396] Subtest fbc-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (5.309s)
[141.836438] [024/133] (883s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping)
[141.929193] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping
[141.955217] Starting dynamic subtest: pipe-A-plane-0
[142.232851] Dynamic subtest pipe-A-plane-0: SUCCESS (0.278s)
[142.242605] Starting dynamic subtest: pipe-A-plane-3
[142.466327] Dynamic subtest pipe-A-plane-3: SUCCESS (0.233s)
[143.599345] Starting dynamic subtest: pipe-B-plane-0
[143.881093] Dynamic subtest pipe-B-plane-0: SUCCESS (0.289s)
[143.889429] Starting dynamic subtest: pipe-B-plane-3
[144.114463] Dynamic subtest pipe-B-plane-3: SUCCESS (0.233s)
[144.430651] Subtest pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping: SUCCESS (2.502s)
[145.285466] [025/133] (879s left) kms_vblank (ts-continuation-dpms-suspend)
[145.374135] Starting subtest: ts-continuation-dpms-suspend
[145.376085] Starting dynamic subtest: pipe-A-eDP-1
[162.835851] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.957s)
[162.849718] Starting dynamic subtest: pipe-D-eDP-1
[180.836837] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.471s)
[180.839183] Subtest ts-continuation-dpms-suspend: SUCCESS (4.431s)
[181.658452] [026/133] (843s left) gem_exec_reloc (basic-cpu-read-active)
[181.734230] Subtest basic-cpu-read-active: SKIP (0.000s)
[181.808939] [027/133] (843s left) kms_cursor_edge_walk (256x256-top-edge)
[181.893397] Starting subtest: 256x256-top-edge
[181.895713] Starting dynamic subtest: pipe-A-eDP-1
[185.363154] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.437s)
[185.374733] Starting dynamic subtest: pipe-D-eDP-1
[188.503019] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.121s)
[188.505274] Subtest 256x256-top-edge: SUCCESS (6.561s)
[189.335794] [028/133] (835s left) gem_ctx_shared (q-smoketest)
[189.401190] Starting subtest: Q-smoketest
[189.403486] Starting dynamic subtest: rcs0
[195.001552] Dynamic subtest rcs0: SUCCESS (5.539s)
[195.014684] Starting dynamic subtest: bcs0
[200.525955] Dynamic subtest bcs0: SUCCESS (5.509s)
[200.534888] Starting dynamic subtest: vcs0
[206.005036] Dynamic subtest vcs0: SUCCESS (5.474s)
[206.015150] Starting dynamic subtest: vcs1
[211.491554] Dynamic subtest vcs1: SUCCESS (5.477s)
[211.502620] Starting dynamic subtest: vecs0
[216.973045] Dynamic subtest vecs0: SUCCESS (5.490s)
[216.981670] Starting dynamic subtest: ccs0
[222.486376] Dynamic subtest ccs0: SUCCESS (5.510s)
[222.488672] Subtest Q-smoketest: SUCCESS (33.005s)
[222.628338] [029/133] (802s left) kms_plane_lowres (tiling-4)
[222.720822] Starting subtest: tiling-4
[222.722917] Starting dynamic subtest: pipe-A-eDP-1
[222.728132] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[223.015352] Starting dynamic subtest: pipe-B-eDP-1
[223.019982] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[223.028291] Starting dynamic subtest: pipe-C-eDP-1
[223.032913] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[223.041290] Starting dynamic subtest: pipe-D-eDP-1
[223.045767] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[223.049857] Subtest tiling-4: SKIP (0.294s)
[223.899920] [030/133] (801s left) kms_ccs (crc-primary-basic-4-tiled-dg2-mc-ccs)
[224.002374] Starting subtest: crc-primary-basic-4-tiled-dg2-mc-ccs
[224.004750] Starting dynamic subtest: pipe-A-eDP-1
[224.028798] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[224.039221] Starting dynamic subtest: pipe-B-eDP-1
[224.060946] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[224.069196] Starting dynamic subtest: pipe-C-eDP-1
[224.091369] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[224.099014] Starting dynamic subtest: pipe-D-eDP-1
[224.121061] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[224.125475] Subtest crc-primary-basic-4-tiled-dg2-mc-ccs: SKIP (0.005s)
[226.176529] [031/133] (799s left) gem_ctx_persistence (engines-mixed-process)
[226.245593] Starting subtest: engines-mixed-process
[226.247665] Starting dynamic subtest: rcs0
[227.959065] Dynamic subtest rcs0: SUCCESS (1.714s)
[227.970135] Starting dynamic subtest: bcs0
[229.867051] Dynamic subtest bcs0: SUCCESS (1.907s)
[229.878085] Starting dynamic subtest: vcs0
[231.944948] Dynamic subtest vcs0: SUCCESS (2.077s)
[231.956196] Starting dynamic subtest: vcs1
[233.905433] Dynamic subtest vcs1: SUCCESS (1.958s)
[233.919135] Starting dynamic subtest: vecs0
[235.955988] Dynamic subtest vecs0: SUCCESS (2.049s)
[235.966816] Starting dynamic subtest: ccs0
[237.934215] Dynamic subtest ccs0: SUCCESS (1.976s)
[237.936727] Subtest engines-mixed-process: SUCCESS (11.688s)
[238.013082] [032/133] (787s left) api_intel_bb (object-reloc-keep-cache)
[238.072597] Starting subtest: object-reloc-keep-cache
[238.077244] Subtest object-reloc-keep-cache: SKIP (0.005s)
[238.237384] [033/133] (787s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-pwrite)
[238.357514] Starting subtest: psr-2p-primscrn-spr-indfb-draw-pwrite
[238.361935] Subtest psr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[238.463164] [034/133] (786s left) sw_sync (alloc_merge_fence)
[238.500614] Starting subtest: alloc_merge_fence
[238.502886] Subtest alloc_merge_fence: SUCCESS (0.000s)
[238.548074] [035/133] (786s left) i915_drm_fdinfo (busy-check-all)
[238.585416] Starting subtest: busy-check-all
[238.587921] Starting dynamic subtest: rcs0
[238.592680] Dynamic subtest rcs0: SKIP (0.004s)
[238.603558] Starting dynamic subtest: bcs0
[238.607772] Dynamic subtest bcs0: SKIP (0.000s)
[238.616573] Starting dynamic subtest: vcs0
[238.621024] Dynamic subtest vcs0: SKIP (0.000s)
[238.630592] Starting dynamic subtest: vcs1
[238.634992] Dynamic subtest vcs1: SKIP (0.000s)
[238.643196] Starting dynamic subtest: vecs0
[238.647668] Dynamic subtest vecs0: SKIP (0.000s)
[238.655823] Starting dynamic subtest: ccs0
[238.660290] Dynamic subtest ccs0: SKIP (0.000s)
[238.664694] Subtest busy-check-all: SKIP (0.009s)
[238.700952] [036/133] (786s left) gem_exec_balancer (persistence)
[238.960957] Starting subtest: persistence
[240.196392] Subtest persistence: SUCCESS (1.235s)
[240.318876] [037/133] (785s left) kms_plane (plane-panning-bottom-right)
[240.415168] Starting subtest: plane-panning-bottom-right
[240.417182] Starting dynamic subtest: pipe-A
[242.061645] Dynamic subtest pipe-A: SUCCESS (1.645s)
[242.071157] Starting dynamic subtest: pipe-B
[244.394773] Dynamic subtest pipe-B: SUCCESS (2.332s)
[244.397197] Subtest plane-panning-bottom-right: SUCCESS (3.980s)
[245.231302] [038/133] (780s left) device_reset (cold-reset-bound)
[245.296873] Starting subtest: cold-reset-bound
[245.301604] Subtest cold-reset-bound: SKIP (0.000s)
[245.371432] [039/133] (780s left) kms_rotation_crc (primary-yf-tiled-reflect-x-90)
[245.471286] Starting subtest: primary-yf-tiled-reflect-x-90
[245.475802] Subtest primary-yf-tiled-reflect-x-90: SKIP (0.001s)
[245.559283] [040/133] (780s left) kms_async_flips (alternate-sync-async-flip)
[245.658461] Starting subtest: alternate-sync-async-flip
[246.033202] Starting dynamic subtest: pipe-A-eDP-1
[248.791784] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.760s)
[249.174165] Starting dynamic subtest: pipe-B-eDP-1
[251.920606] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.752s)
[252.303314] Starting dynamic subtest: pipe-C-eDP-1
[255.051625] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.755s)
[255.418234] Starting dynamic subtest: pipe-D-eDP-1
[258.178589] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.767s)
[258.180600] Subtest alternate-sync-async-flip: SUCCESS (12.520s)
[259.340706] [041/133] (766s left) gem_userptr_blits (unsync-overlap)
[259.402506] Subtest unsync-overlap: SKIP (0.000s)
[259.527091] [042/133] (766s left) kms_flip (bo-too-big-interruptible)
[259.619907] Starting subtest: bo-too-big-interruptible
[259.622218] Starting dynamic subtest: A-eDP1
[260.885010] Dynamic subtest A-eDP1: SUCCESS (1.264s)
[260.894444] Starting dynamic subtest: B-eDP1
[262.112891] Dynamic subtest B-eDP1: SUCCESS (1.226s)
[262.124088] Starting dynamic subtest: C-eDP1
[263.351281] Dynamic subtest C-eDP1: SUCCESS (1.237s)
[263.360062] Starting dynamic subtest: D-eDP1
[264.594327] Dynamic subtest D-eDP1: SUCCESS (1.241s)
[264.596898] Subtest bo-too-big-interruptible: SUCCESS (4.976s)
[265.800370] [043/133] (759s left) gem_ctx_param (invalid-param-set)
[265.856131] Starting subtest: invalid-param-set
[265.858563] Subtest invalid-param-set: SUCCESS (0.000s)
[265.943520] [044/133] (759s left) gem_render_copy_redux (flink-interruptible)
[266.006352] Starting subtest: flink-interruptible
[266.855778] Subtest flink-interruptible: SUCCESS (0.850s)
[266.987310] [045/133] (758s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-0)
[267.090216] Starting subtest: linear-max-hw-stride-64bpp-rotate-0
[268.125726] Subtest linear-max-hw-stride-64bpp-rotate-0: SUCCESS (1.034s)
[268.250568] [046/133] (757s left) syncobj_timeline (multi-wait-submitted-signaled)
[268.313991] Starting subtest: multi-wait-submitted-signaled
[268.316252] Subtest multi-wait-submitted-signaled: SUCCESS (0.001s)
[268.404940] [047/133] (757s left) prime_mmap (test_reprime)
[268.457219] Starting subtest: test_reprime
[268.458763] Starting dynamic subtest: test_reprime-smem
[268.491147] Dynamic subtest test_reprime-smem: SUCCESS (0.034s)
[268.493416] Subtest test_reprime: SUCCESS (0.035s)
[268.604073] [048/133] (757s left) gem_exec_fence (syncobj-wait)
[269.053212] Starting subtest: syncobj-wait
[270.922022] Subtest syncobj-wait: SUCCESS (1.866s)
[271.285018] [049/133] (754s left) kms_atomic_interruptible (atomic-setmode)
[271.376888] Starting subtest: atomic-setmode
[271.379002] Starting dynamic subtest: pipe-A-eDP-1
[278.293160] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.915s)
[278.295553] Subtest atomic-setmode: SUCCESS (6.916s)
[279.167690] [050/133] (746s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs)
[279.269056] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs
[279.271866] Starting dynamic subtest: pipe-A-eDP-1
[279.537871] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.239s)
[279.549313] Starting dynamic subtest: pipe-B-eDP-1
[280.831503] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.291s)
[280.840724] Starting dynamic subtest: pipe-C-eDP-1
[282.045539] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.216s)
[282.056819] Starting dynamic subtest: pipe-D-eDP-1
[283.270945] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.223s)
[283.273175] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SUCCESS (3.976s)
[284.407390] [051/133] (741s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-upscaling)
[284.501310] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-upscaling
[284.505908] Subtest flip-64bpp-ytile-to-16bpp-ytile-upscaling: SKIP (0.000s)
[284.573877] [052/133] (741s left) sysfs_heartbeat_interval (nopreempt)
[284.632005] Starting subtest: nopreempt
[284.634298] Starting dynamic subtest: vecs0
[293.931413] Dynamic subtest vecs0: SUCCESS (9.299s)
[293.942525] Starting dynamic subtest: ccs0
[297.919231] Dynamic subtest ccs0: SUCCESS (3.988s)
[297.928795] Starting dynamic subtest: vcs0
[307.882356] Dynamic subtest vcs0: SUCCESS (9.960s)
[307.893857] Starting dynamic subtest: bcs0
[311.831350] Dynamic subtest bcs0: SUCCESS (3.949s)
[311.842685] Starting dynamic subtest: rcs0
[315.882847] Dynamic subtest rcs0: SUCCESS (4.050s)
[315.893852] Starting dynamic subtest: vcs1
[325.930395] Dynamic subtest vcs1: SUCCESS (10.046s)
[325.933393] Subtest nopreempt: SUCCESS (41.302s)
[326.007180] [053/133] (700s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted-signaled)
[326.064207] Starting subtest: etime-multi-wait-all-for-submit-submitted-signaled
[326.171364] Subtest etime-multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.107s)
[326.244594] [054/133] (699s left) kms_big_fb (y-tiled-16bpp-rotate-270)
[326.352037] Starting subtest: y-tiled-16bpp-rotate-270
[326.356589] Subtest y-tiled-16bpp-rotate-270: SKIP (0.000s)
[326.440970] [055/133] (699s left) kms_hdmi_inject (inject-4k)
[326.798255] Starting subtest: inject-4k
[327.107682] Subtest inject-4k: SUCCESS (0.312s)
[327.669481] [056/133] (698s left) kms_lease (simple-lease)
[327.768398] Starting subtest: simple-lease
[327.770777] Starting dynamic subtest: pipe-A-eDP-1
[328.262925] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.494s)
[328.272755] Starting dynamic subtest: pipe-B-eDP-1
[329.739354] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.474s)
[329.748246] Starting dynamic subtest: pipe-C-eDP-1
[331.142002] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.400s)
[331.151802] Starting dynamic subtest: pipe-D-eDP-1
[332.538385] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.395s)
[332.540802] Subtest simple-lease: SUCCESS (4.771s)
[333.333625] [057/133] (692s left) kms_psr2_sf (fbc-pr-cursor-plane-move-continuous-exceed-sf)
[333.439881] Starting subtest: fbc-pr-cursor-plane-move-continuous-exceed-sf
[333.443918] Subtest fbc-pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.000s)
[333.512504] [058/133] (692s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-wc)
[333.617317] Starting subtest: fbcpsr-rgb101010-draw-mmap-wc
[340.223804] Subtest fbcpsr-rgb101010-draw-mmap-wc: SUCCESS (6.608s)
[340.336518] [059/133] (686s left) kms_big_fb (4-tiled-16bpp-rotate-0)
[340.430246] Starting subtest: 4-tiled-16bpp-rotate-0
[341.729358] Subtest 4-tiled-16bpp-rotate-0: SUCCESS (1.301s)
[341.863446] [060/133] (684s left) kms_hdr (invalid-hdr)
[341.972322] Starting subtest: invalid-hdr
[344.174283] Starting dynamic subtest: pipe-A-eDP-1
[347.078451] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.904s)
[347.080844] Subtest invalid-hdr: SUCCESS (5.107s)
[347.194535] [061/133] (679s left) gem_mmap_wc (write-gtt-read-wc)
[347.257074] Subtest write-gtt-read-wc: SKIP (0.000s)
[347.313431] [062/133] (679s left) kms_psr (fbc-psr2-cursor-mmap-gtt)
[347.391936] Starting subtest: fbc-psr2-cursor-mmap-gtt
[347.394196] Starting dynamic subtest: eDP-1
[347.398292] Dynamic subtest eDP-1: SKIP (0.000s)
[347.402304] Subtest fbc-psr2-cursor-mmap-gtt: SKIP (0.001s)
[347.463683] [063/133] (679s left) gem_create (create-clear)
[347.516291] Starting subtest: create-clear
[347.518188] Starting dynamic subtest: smem0
[380.222050] Dynamic subtest smem0: SUCCESS (32.695s)
[380.224162] Subtest create-clear: SUCCESS (32.697s)
[380.420228] [064/133] (646s left) kms_psr (psr-cursor-mmap-cpu)
[380.517259] Starting subtest: psr-cursor-mmap-cpu
[380.519557] Starting dynamic subtest: eDP-1
[382.201415] Dynamic subtest eDP-1: SUCCESS (1.683s)
[382.203307] Subtest psr-cursor-mmap-cpu: SUCCESS (1.684s)
[383.031562] [065/133] (643s left) gem_mmap_wc (pf-nonblock)
[383.093059] Subtest pf-nonblock: SKIP (0.000s)
[383.152943] [066/133] (643s left) syncobj_timeline (invalid-single-wait-all-unsubmitted)
[383.222875] Starting subtest: invalid-single-wait-all-unsubmitted
[383.225324] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[383.334496] [067/133] (643s left) kms_addfb_basic (unused-handle)
[383.398579] Starting subtest: unused-handle
[383.400500] Subtest unused-handle: SUCCESS (0.000s)
[383.519326] [068/133] (643s left) kms_flip (flip-vs-panning-vs-hang)
[383.620050] Starting subtest: flip-vs-panning-vs-hang
[383.622289] Starting dynamic subtest: A-eDP1
[414.044086] Dynamic subtest A-eDP1: SUCCESS (30.422s)
[414.053006] Starting dynamic subtest: D-eDP1
[444.047294] Dynamic subtest D-eDP1: SUCCESS (30.005s)
[444.049507] Subtest flip-vs-panning-vs-hang: SUCCESS (60.431s)
[445.233054] [069/133] (581s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-upscaling)
[445.337134] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-upscaling
[445.339250] Starting dynamic subtest: pipe-A-default-mode
[448.010473] Dynamic subtest pipe-A-default-mode: SUCCESS (2.672s)
[448.012679] Subtest flip-64bpp-4tile-to-16bpp-4tile-upscaling: SUCCESS (2.674s)
[448.818824] [070/133] (578s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-5)
[448.914268] Starting subtest: planes-unity-scaling-downscale-factor-0-5
[448.916508] Starting dynamic subtest: pipe-A
[448.935013] Dynamic subtest pipe-A: SKIP (0.016s)
[448.944650] Starting dynamic subtest: pipe-B
[448.975011] Dynamic subtest pipe-B: SKIP (0.040s)
[448.986631] Starting dynamic subtest: pipe-C
[449.004533] Dynamic subtest pipe-C: SKIP (0.030s)
[449.013386] Starting dynamic subtest: pipe-D
[449.035383] Dynamic subtest pipe-D: SKIP (0.029s)
[449.038928] Subtest planes-unity-scaling-downscale-factor-0-5: SKIP (0.118s)
[449.130069] [071/133] (577s left) gem_exec_balancer (bonded-chain)
[449.188384] Starting subtest: bonded-chain
[456.183291] Subtest bonded-chain: SUCCESS (6.993s)
[456.516182] [072/133] (570s left) kms_color (gamma)
[456.610092] Starting subtest: gamma
[456.612261] Starting dynamic subtest: pipe-A-eDP-1
[457.329577] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.719s)
[457.340851] Starting dynamic subtest: pipe-B-eDP-1
[458.719311] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.389s)
[458.728165] Starting dynamic subtest: pipe-C-eDP-1
[460.043841] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.326s)
[460.052863] Starting dynamic subtest: pipe-D-eDP-1
[461.439656] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.395s)
[461.441949] Subtest gamma: SUCCESS (4.831s)
[462.329279] [073/133] (564s left) i915_hangman (gt-error-state-capture)
[462.393996] Starting subtest: gt-error-state-capture
[462.396373] Starting dynamic subtest: rcs0
[473.869386] Dynamic subtest rcs0: SUCCESS (11.474s)
[473.882191] Starting dynamic subtest: bcs0
[485.836087] Dynamic subtest bcs0: SUCCESS (11.963s)
[485.847957] Starting dynamic subtest: vcs0
[497.805237] Dynamic subtest vcs0: SUCCESS (11.965s)
[497.816363] Starting dynamic subtest: vcs1
[508.813534] Dynamic subtest vcs1: SUCCESS (11.004s)
[508.825362] Starting dynamic subtest: vecs0
[518.797148] Dynamic subtest vecs0: SUCCESS (9.978s)
[518.808975] Starting dynamic subtest: ccs0
[528.844780] Dynamic subtest ccs0: SUCCESS (10.044s)
[528.847708] Subtest gt-error-state-capture: SUCCESS (66.445s)
[528.955341] [074/133] (498s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-gtt)
[529.060400] Starting subtest: psr-rgb565-draw-mmap-gtt
[529.064765] Subtest psr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[529.181535] [075/133] (497s left) kms_psr (fbc-psr-cursor-plane-onoff)
[529.275339] Starting subtest: fbc-psr-cursor-plane-onoff
[529.277578] Starting dynamic subtest: eDP-1
[529.281914] Dynamic subtest eDP-1: SKIP (0.000s)
[529.286063] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.001s)
[529.345662] [076/133] (497s left) gen9_exec_parse (basic-rejected-ctx-param)
[529.409297] Subtest basic-rejected-ctx-param: SKIP (0.000s)
[529.485377] [077/133] (497s left) gem_lmem_swapping (heavy-multi)
[529.527731] Subtest heavy-multi: SKIP (0.000s)
[529.574228] [078/133] (497s left) perf (sysctl-defaults)
[529.633993] Starting subtest: sysctl-defaults
[529.636291] Subtest sysctl-defaults: SUCCESS (0.000s)
[529.706076] [079/133] (497s left) sriov_basic (bind-unbind-vf)
[529.759614] Starting subtest: bind-unbind-vf
[529.761569] Starting dynamic subtest: vf-1
[529.835613] Dynamic subtest vf-1: FAIL (0.013s)
[529.846496] Starting dynamic subtest: vf-2
[529.909575] Dynamic subtest vf-2: FAIL (0.004s)
[529.919689] Starting dynamic subtest: vf-3
[529.978678] Dynamic subtest vf-3: FAIL (0.003s)
[529.988045] Starting dynamic subtest: vf-4
[530.047301] Dynamic subtest vf-4: FAIL (0.004s)
[530.057176] Starting dynamic subtest: vf-5
[530.116061] Dynamic subtest vf-5: FAIL (0.003s)
[530.125431] Starting dynamic subtest: vf-6
[530.188332] Dynamic subtest vf-6: FAIL (0.004s)
[530.197920] Starting dynamic subtest: vf-7
[530.260997] Dynamic subtest vf-7: FAIL (0.003s)
[530.271571] Starting dynamic subtest: vf-random
[530.333848] Dynamic subtest vf-random: FAIL (0.004s)
[530.343298] Starting dynamic subtest: vf-last
[530.404614] Dynamic subtest vf-last: FAIL (0.441s)
[530.406348] Subtest bind-unbind-vf: FAIL (0.487s)
[530.443603] [080/133] (496s left) gem_exec_schedule (preempt-user)
[530.519336] Starting subtest: preempt-user
[530.521133] Starting dynamic subtest: rcs0
[530.535453] Dynamic subtest rcs0: SUCCESS (0.022s)
[530.546398] Starting dynamic subtest: bcs0
[530.554095] Dynamic subtest bcs0: SUCCESS (0.018s)
[530.565604] Starting dynamic subtest: vcs0
[530.574893] Dynamic subtest vcs0: SUCCESS (0.020s)
[530.583825] Starting dynamic subtest: vcs1
[530.596245] Dynamic subtest vcs1: SUCCESS (0.021s)
[530.608761] Starting dynamic subtest: vecs0
[530.616811] Dynamic subtest vecs0: SUCCESS (0.020s)
[530.625286] Starting dynamic subtest: ccs0
[530.639643] Dynamic subtest ccs0: SUCCESS (0.022s)
[530.641495] Subtest preempt-user: SUCCESS (0.127s)
[531.964596] [081/133] (495s left) gem_mmap (basic-small-bo)
[532.013865] Subtest basic-small-bo: SKIP (0.000s)
[532.081261] [082/133] (495s left) kms_cursor_crc (cursor-onscreen-64x64)
[532.245942] Starting subtest: cursor-onscreen-64x64
[532.250922] Starting dynamic subtest: pipe-A-eDP-1
[534.353145] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.103s)
[534.382447] Starting dynamic subtest: pipe-D-eDP-1
[537.183194] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.807s)
[537.185011] Subtest cursor-onscreen-64x64: SUCCESS (4.935s)
[538.035989] [083/133] (489s left) syncobj_wait (wait-delayed-signal)
[538.099359] Starting subtest: wait-delayed-signal
[538.201375] Subtest wait-delayed-signal: SUCCESS (0.103s)
[538.277964] [084/133] (489s left) gem_mmap_gtt (cpuset-big-copy)
[538.336839] Subtest cpuset-big-copy: SKIP (0.000s)
[538.395020] [085/133] (489s left) gem_exec_schedule (preempt-other-chain)
[538.464156] Starting subtest: preempt-other-chain
[538.466401] Starting dynamic subtest: rcs0
[538.539689] Dynamic subtest rcs0: SUCCESS (0.082s)
[538.549758] Starting dynamic subtest: bcs0
[538.627967] Dynamic subtest bcs0: SUCCESS (0.087s)
[538.647274] Starting dynamic subtest: vcs0
[538.708741] Dynamic subtest vcs0: SUCCESS (0.079s)
[538.718841] Starting dynamic subtest: vcs1
[538.800397] Dynamic subtest vcs1: SUCCESS (0.091s)
[538.811929] Starting dynamic subtest: vecs0
[538.880245] Dynamic subtest vecs0: SUCCESS (0.079s)
[538.890102] Starting dynamic subtest: ccs0
[538.964776] Dynamic subtest ccs0: SUCCESS (0.084s)
[538.967151] Subtest preempt-other-chain: SUCCESS (0.508s)
[539.966179] [086/133] (487s left) syncobj_timeline (wait-all-for-submit-delayed-submit)
[540.022629] Starting subtest: wait-all-for-submit-delayed-submit
[540.126189] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.103s)
[540.213251] [087/133] (487s left) kms_big_fb (yf-tiled-32bpp-rotate-0)
[540.310998] Starting subtest: yf-tiled-32bpp-rotate-0
[540.315232] Subtest yf-tiled-32bpp-rotate-0: SKIP (0.000s)
[540.404344] [088/133] (487s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[540.496395] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-wc
[547.454423] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (6.959s)
[547.570410] [089/133] (480s left) kms_psr2_sf (psr2-overlay-primary-update-sf-dmg-area)
[547.685406] Starting subtest: psr2-overlay-primary-update-sf-dmg-area
[547.687835] Starting dynamic subtest: pipe-A-eDP-1
[553.476401] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.796s)
[553.488286] Starting dynamic subtest: pipe-B-eDP-1
[559.974435] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.495s)
[559.976780] Subtest psr2-overlay-primary-update-sf-dmg-area: SUCCESS (12.294s)
[560.815395] [090/133] (466s left) i915_selftest (live)
[564.072934] Starting subtest: live
[564.075485] Starting dynamic subtest: sanitycheck
[568.414907] Dynamic subtest sanitycheck: SUCCESS (4.341s)
[568.424649] Starting dynamic subtest: uncore
[572.954419] Dynamic subtest uncore: SUCCESS (4.537s)
[572.964193] Starting dynamic subtest: workarounds
[577.517520] Dynamic subtest workarounds: SUCCESS (4.560s)
[577.526546] Starting dynamic subtest: gt_engines
[581.978363] Dynamic subtest gt_engines: SUCCESS (4.459s)
[581.986727] Starting dynamic subtest: gt_timelines
[599.611530] Dynamic subtest gt_timelines: SUCCESS (17.627s)
[599.619998] Starting dynamic subtest: gt_contexts
[603.947155] Dynamic subtest gt_contexts: SUCCESS (4.332s)
[603.956918] Starting dynamic subtest: gt_lrc
[608.258161] Dynamic subtest gt_lrc: SUCCESS (4.308s)
[608.267586] Starting dynamic subtest: gt_mocs
[611.142402] Dynamic subtest gt_mocs: SUCCESS (2.882s)
[611.152373] Starting dynamic subtest: gt_pm
[617.896065] Dynamic subtest gt_pm: SUCCESS (6.750s)
[617.906148] Starting dynamic subtest: gt_heartbeat
[622.257553] Dynamic subtest gt_heartbeat: SUCCESS (4.358s)
[622.271984] Starting dynamic subtest: gt_tlb
[631.483382] Dynamic subtest gt_tlb: SUCCESS (9.222s)
[631.497446] Starting dynamic subtest: requests
[644.936915] Dynamic subtest requests: SUCCESS (13.450s)
[644.943767] Starting dynamic subtest: migrate
[650.631713] Dynamic subtest migrate: SUCCESS (5.690s)
[650.643721] Starting dynamic subtest: active
[654.975812] Dynamic subtest active: SUCCESS (4.342s)
[654.986784] Starting dynamic subtest: objects
[658.231456] Dynamic subtest objects: SUCCESS (3.253s)
[658.241215] Starting dynamic subtest: mman
[661.565677] Dynamic subtest mman: SUCCESS (3.333s)
[661.581898] Starting dynamic subtest: dmabuf
[665.879691] Dynamic subtest dmabuf: SUCCESS (4.313s)
[665.886758] Starting dynamic subtest: vma
[670.166015] Dynamic subtest vma: SUCCESS (4.285s)
[670.175019] Starting dynamic subtest: coherency
[675.186174] Dynamic subtest coherency: SUCCESS (5.019s)
[675.195329] Starting dynamic subtest: gtt
[686.988885] Dynamic subtest gtt: SUCCESS (11.802s)
[686.998319] Starting dynamic subtest: gem
[692.642448] Dynamic subtest gem: SUCCESS (5.652s)
[692.650212] Starting dynamic subtest: evict
[698.079190] Dynamic subtest evict: SUCCESS (5.435s)
[698.088547] Starting dynamic subtest: hugepages
[706.791017] Dynamic subtest hugepages: SUCCESS (8.709s)
[706.803902] Starting dynamic subtest: gem_contexts
[733.776528] Dynamic subtest gem_contexts: SUCCESS (26.986s)
[733.785915] Starting dynamic subtest: client
[738.055423] Dynamic subtest client: SUCCESS (4.278s)
[738.064936] Starting dynamic subtest: gem_migrate
[742.393683] Dynamic subtest gem_migrate: SUCCESS (4.337s)
[742.403732] Starting dynamic subtest: reset
[746.739887] Dynamic subtest reset: SUCCESS (4.345s)
[746.749719] Starting dynamic subtest: memory_region
[749.477530] Dynamic subtest memory_region: SUCCESS (2.737s)
[749.487203] Starting dynamic subtest: hangcheck
[762.896483] Dynamic subtest hangcheck: SUCCESS (13.417s)
[762.907203] Starting dynamic subtest: execlists
[767.181595] Dynamic subtest execlists: SUCCESS (4.282s)
[767.191068] Starting dynamic subtest: ring_submission
[771.457462] Dynamic subtest ring_submission: SUCCESS (4.275s)
[771.468173] Starting dynamic subtest: perf
[775.746169] Dynamic subtest perf: SUCCESS (4.287s)
[775.755249] Starting dynamic subtest: slpc
[789.713191] Dynamic subtest slpc: SUCCESS (13.965s)
[789.729275] Starting dynamic subtest: guc
[793.564855] Dynamic subtest guc: SUCCESS (3.850s)
[793.574815] Starting dynamic subtest: guc_multi_lrc
[796.221444] Dynamic subtest guc_multi_lrc: SUCCESS (2.656s)
[796.230375] Starting dynamic subtest: guc_hang
[801.175077] Dynamic subtest guc_hang: SUCCESS (4.952s)
[801.185081] Starting dynamic subtest: late_gt_pm
[805.454820] Dynamic subtest late_gt_pm: SUCCESS (4.278s)
[805.457425] Subtest live: SUCCESS (241.369s)
[805.483339] [FACT i915_selftest (live)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[805.484003] [FACT i915_selftest (live)] deleted: kernel.kmod_is_loaded.i915: true
[805.487205] [091/133] (222s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0)
[807.039441] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0
[808.112151] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.072s)
[808.240801] [FACT kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[808.242039] [FACT kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0)] new: kernel.kmod_is_loaded.i915: true
[808.246066] [092/133] (219s left) kms_feature_discovery (psr2)
[808.354338] Starting subtest: psr2
[808.356177] Subtest psr2: SUCCESS (0.000s)
[808.430095] [093/133] (219s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[808.521529] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[808.525113] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[808.616960] [094/133] (219s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-fullscreen)
[808.710808] Starting subtest: fbc-1p-primscrn-spr-indfb-fullscreen
[812.743778] Subtest fbc-1p-primscrn-spr-indfb-fullscreen: SUCCESS (4.036s)
[813.870817] [095/133] (214s left) kms_pm_rpm (package-g7)
[816.106716] Starting subtest: package-g7
[816.111804] Subtest package-g7: SKIP (0.000s)
[816.487997] [096/133] (211s left) i915_pm_rpm (gem-mmap-type)
[818.117814] Starting subtest: gem-mmap-type
[818.120237] Starting dynamic subtest: wb-smem0
[822.149309] Dynamic subtest wb-smem0: SUCCESS (4.034s)
[822.158854] Starting dynamic subtest: wc-smem0
[825.334593] Dynamic subtest wc-smem0: SUCCESS (3.183s)
[825.343717] Starting dynamic subtest: uc-smem0
[828.215236] Dynamic subtest uc-smem0: SUCCESS (2.880s)
[828.217668] Subtest gem-mmap-type: SUCCESS (10.103s)
[828.305079] [097/133] (199s left) gem_ctx_persistence (many-contexts)
[828.374024] Starting subtest: many-contexts
[866.067716] Subtest many-contexts: SUCCESS (37.690s)
[866.164976] [098/133] (161s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[866.262110] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[866.266781] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[866.362490] [099/133] (161s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-pwrite)
[866.454031] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-pwrite
[866.458464] Subtest fbc-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[866.560536] [100/133] (161s left) kms_ccs (bad-rotation-90-4-tiled-mtl-rc-ccs)
[866.661356] Starting subtest: bad-rotation-90-4-tiled-mtl-rc-ccs
[866.663571] Starting dynamic subtest: pipe-A-eDP-1
[866.804607] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.134s)
[866.815744] Starting dynamic subtest: pipe-B-eDP-1
[866.836087] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[866.844109] Starting dynamic subtest: pipe-C-eDP-1
[866.863744] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.002s)
[866.871444] Starting dynamic subtest: pipe-D-eDP-1
[866.891009] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.002s)
[866.892939] Subtest bad-rotation-90-4-tiled-mtl-rc-ccs: SUCCESS (0.143s)
[869.086463] [101/133] (159s left) perf_pmu (busy-no-semaphores)
[869.135882] Starting subtest: busy-no-semaphores
[869.138177] Starting dynamic subtest: rcs0
[869.682169] Dynamic subtest rcs0: SUCCESS (0.544s)
[869.694149] Starting dynamic subtest: bcs0
[870.233205] Dynamic subtest bcs0: SUCCESS (0.548s)
[870.245510] Starting dynamic subtest: vcs0
[870.785477] Dynamic subtest vcs0: SUCCESS (0.548s)
[870.797109] Starting dynamic subtest: vcs1
[872.436448] Dynamic subtest vcs1: SUCCESS (1.647s)
[872.447365] Starting dynamic subtest: vecs0
[874.418092] Dynamic subtest vecs0: SUCCESS (1.979s)
[874.430160] Starting dynamic subtest: ccs0
[876.403533] Dynamic subtest ccs0: SUCCESS (1.981s)
[876.406020] Subtest busy-no-semaphores: SUCCESS (7.265s)
[876.517582] [102/133] (151s left) gem_pxp (verify-pxp-execution-after-suspend-resume)
[876.635605] Starting subtest: verify-pxp-execution-after-suspend-resume
[884.511631] Subtest verify-pxp-execution-after-suspend-resume: SUCCESS (7.876s)
[884.657382] [103/133] (143s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-sf)
[884.756708] Starting subtest: fbc-psr2-overlay-plane-move-continuous-sf
[884.758963] Starting dynamic subtest: pipe-A-eDP-1
[884.840971] Dynamic subtest pipe-A-eDP-1: SKIP (0.088s)
[884.851698] Starting dynamic subtest: pipe-B-eDP-1
[886.012356] Dynamic subtest pipe-B-eDP-1: SKIP (1.168s)
[886.017050] Subtest fbc-psr2-overlay-plane-move-continuous-sf: SKIP (1.259s)
[887.196139] [104/133] (141s left) kms_content_protection (dp-mst-type-1-suspend-resume)
[887.344866] Starting subtest: dp-mst-type-1-suspend-resume
[887.349229] Subtest dp-mst-type-1-suspend-resume: SKIP (0.000s)
[887.492361] [105/133] (140s left) gem_spin_batch (resubmit)
[887.550885] Starting subtest: resubmit
[887.553174] Starting dynamic subtest: rcs0
[887.557969] Dynamic subtest rcs0: SUCCESS (0.006s)
[887.568627] Starting dynamic subtest: bcs0
[887.570869] Dynamic subtest bcs0: SUCCESS (0.003s)
[887.580402] Starting dynamic subtest: vcs0
[887.582216] Dynamic subtest vcs0: SUCCESS (0.003s)
[887.590461] Starting dynamic subtest: vcs1
[887.592348] Dynamic subtest vcs1: SUCCESS (0.003s)
[887.600587] Starting dynamic subtest: vecs0
[887.602469] Dynamic subtest vecs0: SUCCESS (0.003s)
[887.610909] Starting dynamic subtest: ccs0
[887.612862] Dynamic subtest ccs0: SUCCESS (0.002s)
[887.614778] Subtest resubmit: SUCCESS (0.025s)
[887.840176] [106/133] (140s left) kms_psr (pr-primary-mmap-cpu)
[887.943298] Starting subtest: pr-primary-mmap-cpu
[887.947483] Subtest pr-primary-mmap-cpu: SKIP (0.000s)
[888.018060] [107/133] (140s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite)
[888.115710] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite
[896.758207] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.643s)
[896.894996] [108/133] (131s left) gem_mmap_gtt (basic-read-write)
[896.958234] Subtest basic-read-write: SKIP (0.000s)
[897.022132] [109/133] (131s left) kms_vrr (max-min)
[897.112440] Starting subtest: max-min
[897.118791] Subtest max-min: SKIP (0.000s)
[897.191979] [110/133] (131s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[897.289666] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-gtt
[897.294058] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[897.392037] [111/133] (131s left) gem_ringfill (basic-all)
[902.897689] Starting subtest: basic-all
[904.066853] Subtest basic-all: SUCCESS (1.168s)
[904.188717] [112/133] (124s left) gem_exec_store (basic)
[904.251487] Starting subtest: basic
[904.366009] Subtest basic: SUCCESS (0.115s)
[904.549056] [113/133] (124s left) kms_cursor_crc (cursor-rapid-movement-256x85)
[904.782870] Starting subtest: cursor-rapid-movement-256x85
[904.830975] Subtest cursor-rapid-movement-256x85: SKIP (0.042s)
[904.996779] [114/133] (123s left) drm_read (empty-nonblock)
[905.139151] Starting subtest: empty-nonblock
[905.141576] Subtest empty-nonblock: SUCCESS (0.000s)
[905.269892] [115/133] (123s left) gem_render_copy (y-tiled-ccs-to-linear)
[905.327769] Starting subtest: y-tiled-ccs-to-linear
[905.332293] Subtest y-tiled-ccs-to-linear: SKIP (0.000s)
[905.514042] [116/133] (123s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-render)
[905.604779] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-render
[905.608800] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[905.716215] [117/133] (123s left) gem_mmap (short-mmap)
[905.777235] Subtest short-mmap: SKIP (0.000s)
[905.843077] [118/133] (123s left) kms_flip (flip-vs-panning-interruptible)
[905.934826] Starting subtest: flip-vs-panning-interruptible
[905.937191] Starting dynamic subtest: A-eDP1
[914.631691] Dynamic subtest A-eDP1: SUCCESS (8.696s)
[914.643302] Starting dynamic subtest: B-eDP1
[923.367145] Dynamic subtest B-eDP1: SUCCESS (8.733s)
[923.380214] Starting dynamic subtest: C-eDP1
[932.088214] Dynamic subtest C-eDP1: SUCCESS (8.718s)
[932.098802] Starting dynamic subtest: D-eDP1
[940.828354] Dynamic subtest D-eDP1: SUCCESS (8.738s)
[940.833646] Subtest flip-vs-panning-interruptible: SUCCESS (34.895s)
[941.987327] [119/133] ( 87s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-upscaling)
[942.091334] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-upscaling
[942.093621] Starting dynamic subtest: pipe-A-default-mode
[944.823452] Dynamic subtest pipe-A-default-mode: SUCCESS (2.733s)
[944.825884] Subtest flip-32bpp-4tile-to-64bpp-4tile-upscaling: SUCCESS (2.734s)
[945.659900] [120/133] ( 83s left) kms_chamelium_frames (dp-crc-single)
[946.035962] Subtest dp-crc-single: SKIP (0.000s)
[946.871192] [121/133] ( 82s left) syncobj_wait (multi-wait-all-for-submit-signaled)
[946.933731] Starting subtest: multi-wait-all-for-submit-signaled
[946.935761] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.000s)
[947.028337] [122/133] ( 82s left) perf (polling)
[947.153684] Starting subtest: polling
[947.155967] Starting dynamic subtest: 0-rcs0
[957.194010] Dynamic subtest 0-rcs0: SUCCESS (10.040s)
[957.204149] Starting dynamic subtest: 1-vcs0
[967.214221] Dynamic subtest 1-vcs0: SUCCESS (10.018s)
[967.217072] Subtest polling: SUCCESS (20.061s)
[967.263373] [123/133] ( 61s left) kms_cursor_legacy (forked-move)
[967.389552] Starting subtest: forked-move
[967.391800] Starting dynamic subtest: pipe-A
[972.847753] Dynamic subtest pipe-A: SUCCESS (5.456s)
[972.860625] Starting dynamic subtest: pipe-B
[978.335582] Dynamic subtest pipe-B: SUCCESS (5.484s)
[978.347407] Starting dynamic subtest: pipe-C
[983.808155] Dynamic subtest pipe-C: SUCCESS (5.470s)
[983.820351] Starting dynamic subtest: pipe-D
[989.283079] Dynamic subtest pipe-D: SUCCESS (5.472s)
[989.295848] Starting dynamic subtest: all-pipes
[994.786466] Dynamic subtest all-pipes: SUCCESS (5.501s)
[994.788972] Subtest forked-move: SUCCESS (27.394s)
[994.930611] [124/133] ( 34s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions-varying-size)
[995.062964] Starting subtest: cursorA-vs-flipA-atomic-transitions-varying-size
[1006.232721] Subtest cursorA-vs-flipA-atomic-transitions-varying-size: SUCCESS (11.168s)
[1007.033028] [125/133] ( 22s left) kms_invalid_mode (zero-hdisplay)
[1007.142150] Starting subtest: zero-hdisplay
[1007.144437] Starting dynamic subtest: pipe-A-eDP-1
[1007.421326] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.284s)
[1007.432055] Starting dynamic subtest: pipe-B-eDP-1
[1007.434559] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[1007.443715] Starting dynamic subtest: pipe-C-eDP-1
[1007.445934] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[1007.456192] Starting dynamic subtest: pipe-D-eDP-1
[1007.458334] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[1007.460539] Subtest zero-hdisplay: SUCCESS (0.290s)
[1008.278235] [126/133] ( 21s left) kms_selftest (drm_dp_mst_helper)
[1008.320237] Starting subtest: drm_dp_mst_helper
[1008.332329] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_mode
[1008.439360] Dynamic subtest drm_test_dp_mst_calc_pbn_mode: SUCCESS (0.107s)
[1008.450801] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_div
[1008.536485] Dynamic subtest drm_test_dp_mst_calc_pbn_div: SUCCESS (0.094s)
[1008.549028] Starting dynamic subtest: drm_test_dp_mst_sideband_msg_req_decode
[1008.657206] Dynamic subtest drm_test_dp_mst_sideband_msg_req_decode: SUCCESS (0.121s)
[1008.659165] Subtest drm_dp_mst_helper: SUCCESS (0.337s)
[1008.824984] [127/133] ( 20s left) kms_flip (flip-vs-expired-vblank)
[1008.911848] Starting subtest: flip-vs-expired-vblank
[1008.914095] Starting dynamic subtest: A-eDP1
[1017.560956] Dynamic subtest A-eDP1: SUCCESS (8.650s)
[1017.571836] Starting dynamic subtest: B-eDP1
[1026.190636] Dynamic subtest B-eDP1: SUCCESS (8.627s)
[1026.200972] Starting dynamic subtest: C-eDP1
[1034.815269] Dynamic subtest C-eDP1: SUCCESS (8.622s)
[1034.824900] Starting dynamic subtest: D-eDP1
[1043.446338] Dynamic subtest D-eDP1: SUCCESS (8.629s)
[1043.451628] Subtest flip-vs-expired-vblank: SUCCESS (34.540s)
[1044.604916] Overall timeout time exceeded, stopping.
[1044.621390] Closing watchdogs
results: parsing output: 0/ for test: kms_cursor_legacy
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: perf
results: parsing output: 3/ for test: gem_tiled_pread_basic
results: parsing output: 4/ for test: kms_chamelium_hpd
results: parsing output: 5/ for test: gem_ctx_param
results: parsing output: 6/ for test: kms_pipe_stress
results: parsing output: 7/ for test: gem_mmap_gtt
results: parsing output: 8/ for test: kms_ccs
results: parsing output: 9/ for test: kms_psr_stress_test
results: parsing output: 10/ for test: syncobj_timeline
results: parsing output: 11/ for test: api_intel_allocator
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: gem_render_copy
results: parsing output: 14/ for test: kms_big_fb
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: kms_flip
results: parsing output: 17/ for test: gem_bad_reloc
results: parsing output: 18/ for test: gem_exec_fence
results: parsing output: 19/ for test: kms_getfb
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: kms_flip
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: kms_plane
results: parsing output: 24/ for test: kms_vblank
results: parsing output: 25/ for test: gem_exec_reloc
results: parsing output: 26/ for test: kms_cursor_edge_walk
results: parsing output: 27/ for test: gem_ctx_shared
results: parsing output: 28/ for test: kms_plane_lowres
results: parsing output: 29/ for test: kms_ccs
results: parsing output: 30/ for test: gem_ctx_persistence
results: parsing output: 31/ for test: api_intel_bb
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: sw_sync
results: parsing output: 34/ for test: i915_drm_fdinfo
results: parsing output: 35/ for test: gem_exec_balancer
results: parsing output: 36/ for test: kms_plane
results: parsing output: 37/ for test: device_reset
results: parsing output: 38/ for test: kms_rotation_crc
results: parsing output: 39/ for test: kms_async_flips
results: parsing output: 40/ for test: gem_userptr_blits
results: parsing output: 41/ for test: kms_flip
results: parsing output: 42/ for test: gem_ctx_param
results: parsing output: 43/ for test: gem_render_copy_redux
results: parsing output: 44/ for test: kms_big_fb
results: parsing output: 45/ for test: syncobj_timeline
results: parsing output: 46/ for test: prime_mmap
results: parsing output: 47/ for test: gem_exec_fence
results: parsing output: 48/ for test: kms_atomic_interruptible
results: parsing output: 49/ for test: kms_ccs
results: parsing output: 50/ for test: kms_flip_scaled_crc
results: parsing output: 51/ for test: sysfs_heartbeat_interval
results: parsing output: 52/ for test: syncobj_timeline
results: parsing output: 53/ for test: kms_big_fb
results: parsing output: 54/ for test: kms_hdmi_inject
results: parsing output: 55/ for test: kms_lease
results: parsing output: 56/ for test: kms_psr2_sf
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: kms_big_fb
results: parsing output: 59/ for test: kms_hdr
results: parsing output: 60/ for test: gem_mmap_wc
results: parsing output: 61/ for test: kms_psr
results: parsing output: 62/ for test: gem_create
results: parsing output: 63/ for test: kms_psr
results: parsing output: 64/ for test: gem_mmap_wc
results: parsing output: 65/ for test: syncobj_timeline
results: parsing output: 66/ for test: kms_addfb_basic
results: parsing output: 67/ for test: kms_flip
results: parsing output: 68/ for test: kms_flip_scaled_crc
results: parsing output: 69/ for test: kms_plane_scaling
results: parsing output: 70/ for test: gem_exec_balancer
results: parsing output: 71/ for test: kms_color
results: parsing output: 72/ for test: i915_hangman
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: kms_psr
results: parsing output: 75/ for test: gen9_exec_parse
results: parsing output: 76/ for test: gem_lmem_swapping
results: parsing output: 77/ for test: perf
results: parsing output: 78/ for test: sriov_basic
results: parsing output: 79/ for test: gem_exec_schedule
results: parsing output: 80/ for test: gem_mmap
results: parsing output: 81/ for test: kms_cursor_crc
results: parsing output: 82/ for test: syncobj_wait
results: parsing output: 83/ for test: gem_mmap_gtt
results: parsing output: 84/ for test: gem_exec_schedule
results: parsing output: 85/ for test: syncobj_timeline
results: parsing output: 86/ for test: kms_big_fb
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: kms_psr2_sf
results: parsing output: 89/ for test: i915_selftest
results: parsing output: 90/ for test: kms_big_fb
results: parsing output: 91/ for test: kms_feature_discovery
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: kms_pm_rpm
results: parsing output: 95/ for test: i915_pm_rpm
results: parsing output: 96/ for test: gem_ctx_persistence
results: parsing output: 97/ for test: kms_big_fb
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: kms_ccs
results: parsing output: 100/ for test: perf_pmu
results: parsing output: 101/ for test: gem_pxp
results: parsing output: 102/ for test: kms_psr2_sf
results: parsing output: 103/ for test: kms_content_protection
results: parsing output: 104/ for test: gem_spin_batch
results: parsing output: 105/ for test: kms_psr
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: gem_mmap_gtt
results: parsing output: 108/ for test: kms_vrr
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: gem_ringfill
results: parsing output: 111/ for test: gem_exec_store
results: parsing output: 112/ for test: kms_cursor_crc
results: parsing output: 113/ for test: drm_read
results: parsing output: 114/ for test: gem_render_copy
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: gem_mmap
results: parsing output: 117/ for test: kms_flip
results: parsing output: 118/ for test: kms_flip_scaled_crc
results: parsing output: 119/ for test: kms_chamelium_frames
results: parsing output: 120/ for test: syncobj_wait
results: parsing output: 121/ for test: perf
results: parsing output: 122/ for test: kms_cursor_legacy
results: parsing output: 123/ for test: kms_cursor_legacy
results: parsing output: 124/ for test: kms_invalid_mode
results: parsing output: 125/ for test: kms_selftest
results: parsing output: 126/ for test: kms_flip
results: parsing output: 127/ for test: kms_ccs
results: no output, setting notrun
results: parsing output: 128/ for test: gem_mmap_wc
results: no output, setting notrun
results: parsing output: 129/ for test: gem_partial_pwrite_pread
results: no output, setting notrun
results: parsing output: 130/ for test: gem_mmap_offset
results: no output, setting notrun
results: parsing output: 131/ for test: kms_pm_dc
results: no output, setting notrun
results: parsing output: 132/ for test: kms_big_fb
results: no output, setting notrun
Done.