[55.463678] Initializing watchdogs
[55.463725]   /dev/watchdog0
[55.477925] [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]
[55.482044] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[55.484230] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[55.487734] [001/130] (960s left) syncobj_timeline (multi-wait-for-submit-available-signaled)
[55.564749] Starting subtest: multi-wait-for-submit-available-signaled
[55.567214] Subtest multi-wait-for-submit-available-signaled: SUCCESS (0.001s)
[55.683858] [002/130] (960s left) dmabuf_sync_file (export-basic)
[55.756293] Starting subtest: export-basic
[55.758219] Subtest export-basic: SUCCESS (0.001s)
[55.802047] [FACT dmabuf_sync_file (export-basic)] new: kernel.kmod_is_loaded.vgem: true
[55.805124] [003/130] (960s left) gem_reg_read (timestamp-monotonic)
[55.868469] Starting subtest: timestamp-monotonic
[60.213568] Subtest timestamp-monotonic: SUCCESS (4.319s)
[60.280997] [004/130] (955s left) kms_plane (plane-panning-bottom-right-suspend)
[60.379966] Starting subtest: plane-panning-bottom-right-suspend
[60.382243] Starting dynamic subtest: pipe-A
[78.139881] Dynamic subtest pipe-A: SUCCESS (2.803s)
[78.148707] Starting dynamic subtest: pipe-B
[96.102174] Dynamic subtest pipe-B: SUCCESS (3.306s)
[96.104562] Subtest plane-panning-bottom-right-suspend: SUCCESS (6.111s)
[96.939222] [005/130] (948s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-render)
[97.046172] Starting subtest: psr-2p-primscrn-cur-indfb-draw-render
[97.051367] Subtest psr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[97.155574] [006/130] (948s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[97.221984] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[97.224665] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[97.334683] [007/130] (948s left) gem_exec_params (secure-non-root)
[97.402964] Starting subtest: secure-non-root
[97.408394] Subtest secure-non-root: SKIP (0.000s)
[97.491815] [008/130] (948s left) gen9_exec_parse (bb-chained)
[97.560183] Subtest bb-chained: SKIP (0.000s)
[97.635121] [009/130] (948s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-gtt)
[97.743176] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-gtt
[97.748304] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[97.840000] [010/130] (948s left) kms_vblank (ts-continuation-dpms-rpm)
[97.935370] Starting subtest: ts-continuation-dpms-rpm
[97.938120] Starting dynamic subtest: pipe-A-eDP-1
[100.451046] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.495s)
[100.466177] Starting dynamic subtest: pipe-D-eDP-1
[102.705301] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.240s)
[102.707886] Subtest ts-continuation-dpms-rpm: SUCCESS (4.738s)
[103.495805] [011/130] (942s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted-submitted)
[103.551252] Starting subtest: multi-wait-all-for-submit-unsubmitted-submitted
[103.658585] Subtest multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.107s)
[103.730034] [012/130] (942s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions-varying-size)
[103.850926] Starting subtest: short-flip-after-cursor-atomic-transitions-varying-size
[104.859234] Subtest short-flip-after-cursor-atomic-transitions-varying-size: SUCCESS (1.003s)
[105.728436] [013/130] (940s left) gem_ctx_sseu (mmap-args)
[105.790177] Subtest mmap-args: SKIP (0.000s)
[105.877921] [014/130] (940s left) kms_cursor_crc (cursor-rapid-movement-512x170)
[106.148341] Starting subtest: cursor-rapid-movement-512x170
[106.153223] Subtest cursor-rapid-movement-512x170: SKIP (0.001s)
[106.303566] [015/130] (939s left) kms_psr (fbc-psr-cursor-render)
[106.405020] Starting subtest: fbc-psr-cursor-render
[106.407557] Starting dynamic subtest: eDP-1
[106.412332] Dynamic subtest eDP-1: SKIP (0.000s)
[106.417228] Subtest fbc-psr-cursor-render: SKIP (0.000s)
[106.472597] [016/130] (939s left) gem_spin_batch (spin-all)
[106.548324] Starting subtest: spin-all
[106.564297] Subtest spin-all: SUCCESS (0.014s)
[108.562362] [017/130] (937s left) kms_chamelium_color (ctm-0-75)
[108.663290] Subtest ctm-0-75: SKIP (0.000s)
[108.703513] [018/130] (937s left) kms_lease (lessee-list)
[108.792602] Starting subtest: lessee-list
[108.795323] Starting dynamic subtest: pipe-A-eDP-1
[108.798126] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.002s)
[108.805242] Starting dynamic subtest: pipe-B-eDP-1
[108.807963] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[108.815168] Starting dynamic subtest: pipe-C-eDP-1
[108.817941] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[108.825113] Starting dynamic subtest: pipe-D-eDP-1
[108.827795] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[108.830302] Subtest lessee-list: SUCCESS (0.007s)
[108.908138] [019/130] (937s left) kms_cursor_crc (cursor-random-256x256)
[109.126366] Starting subtest: cursor-random-256x256
[109.129098] Starting dynamic subtest: pipe-A-eDP-1
[112.973488] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.840s)
[113.005287] Starting dynamic subtest: pipe-D-eDP-1
[117.479913] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.478s)
[117.484444] Subtest cursor-random-256x256: SUCCESS (8.341s)
[118.297959] [020/130] (928s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-cpu)
[118.403338] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-cpu
[118.408180] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[118.513787] [021/130] (927s left) perf_pmu (idle-no-semaphores)
[118.557652] Starting subtest: idle-no-semaphores
[118.560312] Starting dynamic subtest: rcs0
[119.115964] Dynamic subtest rcs0: SUCCESS (0.558s)
[119.127321] Starting dynamic subtest: bcs0
[119.642154] Dynamic subtest bcs0: SUCCESS (0.525s)
[119.652873] Starting dynamic subtest: vcs0
[120.164608] Dynamic subtest vcs0: SUCCESS (0.521s)
[120.175364] Starting dynamic subtest: vcs1
[120.689839] Dynamic subtest vcs1: SUCCESS (0.524s)
[120.700346] Starting dynamic subtest: vecs0
[121.212678] Dynamic subtest vecs0: SUCCESS (0.521s)
[121.222786] Starting dynamic subtest: ccs0
[121.734179] Dynamic subtest ccs0: SUCCESS (0.521s)
[121.737645] Subtest idle-no-semaphores: SUCCESS (3.174s)
[121.823750] [022/130] (924s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[121.928089] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc
[121.932953] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[122.010975] [023/130] (924s left) kms_rotation_crc (sprite-rotation-180)
[122.106560] Starting subtest: sprite-rotation-180
[127.167158] Subtest sprite-rotation-180: SUCCESS (5.056s)
[128.362219] [024/130] (918s left) i915_pm_rc6_residency (rc6-fence)
[128.428476] Starting subtest: rc6-fence
[128.457931] Starting dynamic subtest: gt0
[140.784994] Dynamic subtest gt0: SUCCESS (12.331s)
[140.793594] Starting dynamic subtest: gt1
[155.438025] Dynamic subtest gt1: SUCCESS (14.650s)
[155.440713] Subtest rc6-fence: SUCCESS (27.015s)
[155.511353] [025/130] (891s left) kms_big_fb (yf-tiled-64bpp-rotate-0)
[155.624247] Starting subtest: yf-tiled-64bpp-rotate-0
[155.629190] Subtest yf-tiled-64bpp-rotate-0: SKIP (0.000s)
[155.724076] [026/130] (890s left) gem_exec_whisper (basic-contexts-priority)
[155.802133] Starting subtest: basic-contexts-priority
[178.930160] Subtest basic-contexts-priority: SUCCESS (23.121s)
[179.112901] [027/130] (867s left) gem_userptr_blits (process-exit-busy)
[179.186983] Starting subtest: process-exit-busy
[179.200247] Subtest process-exit-busy: SUCCESS (0.015s)
[179.340344] [028/130] (867s left) syncobj_basic (illegal-fd-to-handle)
[179.401844] Starting subtest: illegal-fd-to-handle
[179.404851] Subtest illegal-fd-to-handle: SUCCESS (0.000s)
[179.507452] [029/130] (867s left) drm_read (invalid-buffer)
[179.677385] Starting subtest: invalid-buffer
[179.680218] Subtest invalid-buffer: SUCCESS (0.000s)
[179.820311] [030/130] (866s left) gem_exec_reloc (basic-cpu-noreloc)
[179.886989] Subtest basic-cpu-noreloc: SKIP (0.000s)
[179.973059] [031/130] (866s left) kms_cursor_legacy (cursor-vs-flip-atomic)
[180.093190] Starting subtest: cursor-vs-flip-atomic
[209.470088] Subtest cursor-vs-flip-atomic: SUCCESS (29.376s)
[210.284719] [032/130] (836s left) gem_exec_fence (syncobj-unused-fence)
[210.741711] Starting subtest: syncobj-unused-fence
[210.747488] Subtest syncobj-unused-fence: SUCCESS (0.006s)
[212.618355] [033/130] (834s left) kms_frontbuffer_tracking (psr-indfb-scaledprimary)
[212.721479] Starting subtest: psr-indfb-scaledprimary
[216.387559] Subtest psr-indfb-scaledprimary: SUCCESS (3.671s)
[216.506093] [034/130] (830s left) gem_gpgpu_fill (basic)
[216.569325] Starting subtest: basic
[216.571983] Starting dynamic subtest: smem
[216.577175] Dynamic subtest smem: SUCCESS (0.008s)
[216.579622] Subtest basic: SUCCESS (0.009s)
[216.679218] [035/130] (830s left) perf (enable-disable)
[216.789495] Starting subtest: enable-disable
[216.791792] Starting dynamic subtest: 0-rcs0
[219.050276] Dynamic subtest 0-rcs0: SUCCESS (2.262s)
[219.061030] Starting dynamic subtest: 1-vcs1
[223.559692] Dynamic subtest 1-vcs1: SUCCESS (4.509s)
[223.562579] Subtest enable-disable: SUCCESS (6.772s)
[223.603204] [036/130] (823s left) kms_atomic_interruptible (universal-setplane-cursor)
[223.701252] Starting subtest: universal-setplane-cursor
[223.703954] Starting dynamic subtest: pipe-A-eDP-1
[231.049814] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.348s)
[231.052492] Subtest universal-setplane-cursor: SUCCESS (7.349s)
[231.889671] [037/130] (815s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu)
[232.002557] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu
[236.552745] Subtest fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu: SUCCESS (4.553s)
[236.682891] [038/130] (810s left) kms_chamelium_hpd (hdmi-hpd-fast)
[237.072926] Subtest hdmi-hpd-fast: SKIP (0.000s)
[237.840877] [039/130] (809s left) sriov_basic (bind-unbind-vf)
[237.904346] Starting subtest: bind-unbind-vf
[237.907023] Starting dynamic subtest: vf-1
[238.000254] Dynamic subtest vf-1: FAIL (0.031s)
[238.009332] Starting dynamic subtest: vf-2
[238.100156] Dynamic subtest vf-2: FAIL (0.003s)
[238.109877] Starting dynamic subtest: vf-3
[238.204151] Dynamic subtest vf-3: FAIL (0.003s)
[238.213167] Starting dynamic subtest: vf-4
[238.302435] Dynamic subtest vf-4: FAIL (0.003s)
[238.317634] Starting dynamic subtest: vf-5
[238.410075] Dynamic subtest vf-5: FAIL (0.003s)
[238.419444] Starting dynamic subtest: vf-6
[238.506095] Dynamic subtest vf-6: FAIL (0.003s)
[238.515132] Starting dynamic subtest: vf-7
[238.602829] Dynamic subtest vf-7: FAIL (0.003s)
[238.611792] Starting dynamic subtest: vf-random
[238.691351] Dynamic subtest vf-random: FAIL (0.003s)
[238.699128] Starting dynamic subtest: vf-last
[238.775802] Dynamic subtest vf-last: FAIL (0.621s)
[238.777963] Subtest bind-unbind-vf: FAIL (0.677s)
[238.807491] [040/130] (808s left) kms_rotation_crc (primary-yf-tiled-reflect-x-180)
[238.904441] Starting subtest: primary-yf-tiled-reflect-x-180
[238.966194] Subtest primary-yf-tiled-reflect-x-180: SKIP (0.059s)
[239.060872] [041/130] (808s left) gem_ctx_exec (basic-close-race)
[239.129166] Starting subtest: basic-close-race
[244.892352] Subtest basic-close-race: SUCCESS (5.761s)
[245.038194] [042/130] (802s left) gem_mmap_offset (bad-flags)
[245.096164] Starting subtest: bad-flags
[245.098950] Subtest bad-flags: SUCCESS (0.000s)
[245.186754] [043/130] (801s left) kms_plane_alpha_blend (alpha-opaque-fb)
[245.289699] Starting subtest: alpha-opaque-fb
[245.725545] Starting dynamic subtest: pipe-A-eDP-1
[246.322937] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.597s)
[246.332290] Starting dynamic subtest: pipe-D-eDP-1
[247.680441] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.357s)
[247.683217] Subtest alpha-opaque-fb: SUCCESS (2.390s)
[248.513420] [044/130] (798s left) kms_dsc (dsc-basic)
[248.614050] Starting subtest: dsc-basic
[248.631170] Subtest dsc-basic: SKIP (0.000s)
[248.680552] [045/130] (798s left) kms_prime (basic-crc-vgem)
[250.959308] Starting subtest: basic-crc-vgem
[250.961380] Starting dynamic subtest: second-to-first
[251.891822] Dynamic subtest second-to-first: SUCCESS (0.937s)
[251.894232] Subtest basic-crc-vgem: SUCCESS (0.937s)
[251.968836] [FACT kms_prime (basic-crc-vgem)] new: kernel.kmod_is_loaded.amdgpu: true
[251.969517] [FACT kms_prime (basic-crc-vgem)] new: kernel.kmod_is_loaded.xe: true
[251.972582] [046/130] (795s left) kms_sequence (get-forked)
[252.093657] Starting subtest: get-forked
[252.095872] Starting dynamic subtest: pipe-A-eDP-1
[253.988322] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.890s)
[253.998644] Starting dynamic subtest: pipe-B-eDP-1
[256.579970] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.592s)
[256.588780] Starting dynamic subtest: pipe-C-eDP-1
[259.081899] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.499s)
[259.092032] Starting dynamic subtest: pipe-D-eDP-1
[261.628328] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.543s)
[261.631011] Subtest get-forked: SUCCESS (9.530s)
[262.411187] [047/130] (784s left) kms_ccs (bad-aux-stride-y-tiled-gen12-rc-ccs)
[262.508728] Starting subtest: bad-aux-stride-y-tiled-gen12-rc-ccs
[262.511340] Starting dynamic subtest: pipe-A-eDP-1
[262.537937] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[262.547597] Starting dynamic subtest: pipe-B-eDP-1
[262.574229] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[262.582254] Starting dynamic subtest: pipe-C-eDP-1
[262.608957] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[262.617190] Starting dynamic subtest: pipe-D-eDP-1
[262.644964] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[262.650091] Subtest bad-aux-stride-y-tiled-gen12-rc-ccs: SKIP (0.003s)
[264.656455] [048/130] (782s left) gem_mmap_gtt (basic-copy)
[264.713146] Subtest basic-copy: SKIP (0.000s)
[264.773769] [049/130] (782s left) kms_feature_discovery (psr2)
[264.872591] Starting subtest: psr2
[264.875158] Subtest psr2: SUCCESS (0.000s)
[264.938351] [050/130] (782s left) gem_exec_schedule (semaphore-resolve)
[265.018196] Starting subtest: semaphore-resolve
[265.053965] Subtest semaphore-resolve: SUCCESS (0.043s)
[265.404549] [051/130] (782s left) kms_hdr (brightness-with-hdr)
[265.492726] Starting subtest: brightness-with-hdr
[265.500413] Subtest brightness-with-hdr: SKIP (0.000s)
[265.563397] [052/130] (781s left) gem_ctx_persistence (legacy-engines-cleanup)
[265.630538] Starting subtest: legacy-engines-cleanup
[265.633101] Starting dynamic subtest: render
[265.711440] Dynamic subtest render: SUCCESS (0.082s)
[265.723706] Starting dynamic subtest: bsd1
[265.761289] Dynamic subtest bsd1: SUCCESS (0.048s)
[265.773313] Starting dynamic subtest: bsd2
[265.814647] Dynamic subtest bsd2: SUCCESS (0.051s)
[265.825910] Starting dynamic subtest: blt
[265.848250] Dynamic subtest blt: SUCCESS (0.032s)
[265.857223] Starting dynamic subtest: vebox
[265.898986] Dynamic subtest vebox: SUCCESS (0.050s)
[265.901626] Subtest legacy-engines-cleanup: SUCCESS (0.270s)
[266.029400] [053/130] (781s left) kms_big_fb (4-tiled-16bpp-rotate-270)
[266.123844] Starting subtest: 4-tiled-16bpp-rotate-270
[266.143309] Subtest 4-tiled-16bpp-rotate-270: SKIP (0.019s)
[266.273228] [054/130] (781s left) gem_exec_schedule (deep)
[266.456103] Starting subtest: deep
[266.458584] Starting dynamic subtest: rcs0
[270.881037] Dynamic subtest rcs0: SKIP (4.420s)
[270.890993] Starting dynamic subtest: vcs0
[281.042784] Dynamic subtest vcs0: SUCCESS (10.162s)
[281.057229] Starting dynamic subtest: vcs1
[291.819137] Dynamic subtest vcs1: SUCCESS (10.771s)
[291.833742] Starting dynamic subtest: vecs0
[303.048051] Dynamic subtest vecs0: SUCCESS (11.227s)
[303.057781] Starting dynamic subtest: ccs0
[314.706222] Dynamic subtest ccs0: SUCCESS (11.654s)
[314.709462] Subtest deep: SUCCESS (48.237s)
[316.554707] [055/130] (731s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-wc)
[316.666899] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-wc
[320.000955] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (3.339s)
[320.122868] [056/130] (727s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[320.224960] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-cpu
[324.532221] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (4.311s)
[324.663112] [057/130] (723s left) kms_ccs (crc-sprite-planes-basic-y-tiled-gen12-rc-ccs-cc)
[324.770633] Starting subtest: crc-sprite-planes-basic-y-tiled-gen12-rc-ccs-cc
[324.772989] Starting dynamic subtest: pipe-A-eDP-1
[324.835079] Dynamic subtest pipe-A-eDP-1: SKIP (0.002s)
[324.845026] Starting dynamic subtest: pipe-B-eDP-1
[324.912086] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[324.922065] Starting dynamic subtest: pipe-C-eDP-1
[324.990946] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[324.999837] Starting dynamic subtest: pipe-D-eDP-1
[325.067045] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[325.072080] Subtest crc-sprite-planes-basic-y-tiled-gen12-rc-ccs-cc: SKIP (0.010s)
[326.902422] [058/130] (720s left) kms_psr (psr-sprite-mmap-cpu)
[327.011138] Starting subtest: psr-sprite-mmap-cpu
[327.013756] Starting dynamic subtest: eDP-1
[328.815561] Dynamic subtest eDP-1: SUCCESS (1.806s)
[328.818173] Subtest psr-sprite-mmap-cpu: SUCCESS (1.806s)
[329.646540] [059/130] (718s left) kms_invalid_mode (bad-vtotal)
[329.755986] Starting subtest: bad-vtotal
[329.758507] Starting dynamic subtest: pipe-A-eDP-1
[330.038090] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.282s)
[330.046709] Starting dynamic subtest: pipe-B-eDP-1
[330.049234] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[330.057493] Starting dynamic subtest: pipe-C-eDP-1
[330.060193] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[330.068353] Starting dynamic subtest: pipe-D-eDP-1
[330.071018] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[330.073351] Subtest bad-vtotal: SUCCESS (0.290s)
[330.867076] [060/130] (716s left) gem_exec_schedule (promotion)
[330.947248] Starting subtest: promotion
[330.949724] Starting dynamic subtest: rcs0
[331.041085] Dynamic subtest rcs0: SUCCESS (0.100s)
[331.049630] Starting dynamic subtest: vcs0
[331.129019] Dynamic subtest vcs0: SUCCESS (0.086s)
[331.138246] Starting dynamic subtest: vcs1
[331.216944] Dynamic subtest vcs1: SUCCESS (0.087s)
[331.228951] Starting dynamic subtest: vecs0
[331.294366] Dynamic subtest vecs0: SUCCESS (0.077s)
[331.306688] Starting dynamic subtest: ccs0
[331.374916] Dynamic subtest ccs0: SUCCESS (0.079s)
[331.377918] Subtest promotion: SUCCESS (0.434s)
[332.627500] [061/130] (715s left) syncobj_timeline (etime-single-wait-all-available-unsubmitted)
[332.684121] Starting subtest: etime-single-wait-all-available-unsubmitted
[332.786343] Subtest etime-single-wait-all-available-unsubmitted: SUCCESS (0.103s)
[332.854568] [062/130] (715s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[332.954960] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip
[332.960162] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[333.053982] [063/130] (714s left) gem_render_copy (yf-tiled-ccs-to-y-tiled-ccs)
[333.106162] Starting subtest: yf-tiled-ccs-to-y-tiled-ccs
[333.111745] Subtest yf-tiled-ccs-to-y-tiled-ccs: SKIP (0.000s)
[333.291761] [064/130] (714s left) kms_flip (2x-flip-vs-wf_vblank-interruptible)
[333.396230] Starting subtest: 2x-flip-vs-wf_vblank-interruptible
[333.407510] Subtest 2x-flip-vs-wf_vblank-interruptible: SKIP (0.010s)
[333.501543] [065/130] (714s left) kms_psr (psr2-cursor-plane-onoff)
[333.610014] Starting subtest: psr2-cursor-plane-onoff
[333.612724] Starting dynamic subtest: eDP-1
[335.339227] Dynamic subtest eDP-1: SUCCESS (1.730s)
[335.342000] Subtest psr2-cursor-plane-onoff: SUCCESS (1.731s)
[336.129585] [066/130] (711s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-blt)
[336.238238] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-blt
[339.441292] Subtest fbc-1p-offscren-pri-shrfb-draw-blt: SUCCESS (3.208s)
[339.564560] [067/130] (708s left) i915_pm_rpm (system-suspend-execbuf)
[341.604792] Starting subtest: system-suspend-execbuf
[399.783697] Subtest system-suspend-execbuf: SUCCESS (43.019s)
[400.142203] [068/130] (663s left) kms_frontbuffer_tracking (fbcpsr-tiling-4)
[400.248692] Starting subtest: fbcpsr-tiling-4
[405.099882] Subtest fbcpsr-tiling-4: SUCCESS (4.853s)
[405.229025] [069/130] (658s left) kms_psr2_sf (pr-overlay-plane-update-continuous-sf)
[410.224886] Starting subtest: pr-overlay-plane-update-continuous-sf
[410.229572] Subtest pr-overlay-plane-update-continuous-sf: SKIP (0.002s)
[411.075470] [070/130] (652s left) i915_suspend (basic-s3-without-i915)
[411.111740] Starting subtest: basic-s3-without-i915
[413.160613] Subtest basic-s3-without-i915: SKIP (2.044s)
[414.554807] [071/130] (648s left) kms_pm_rpm (modeset-lpsp-stress-no-wait)
[416.701731] Starting subtest: modeset-lpsp-stress-no-wait
[426.222162] Subtest modeset-lpsp-stress-no-wait: SUCCESS (9.517s)
[426.293005] [072/130] (637s left) kms_psr2_sf (pr-overlay-plane-update-sf-dmg-area)
[431.317962] Starting subtest: pr-overlay-plane-update-sf-dmg-area
[431.323199] Subtest pr-overlay-plane-update-sf-dmg-area: SKIP (0.002s)
[432.177358] [073/130] (631s left) gem_exec_balancer (nohangcheck)
[432.432931] Starting subtest: nohangcheck
[433.184070] Subtest nohangcheck: SUCCESS (0.750s)
[433.304578] [074/130] (630s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling)
[433.409462] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling
[433.411803] Starting dynamic subtest: pipe-A-default-mode
[433.693134] Dynamic subtest pipe-A-default-mode: SKIP (0.279s)
[433.698242] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling: SKIP (0.280s)
[434.502594] [075/130] (628s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-blt)
[434.607423] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-blt
[434.612159] Subtest psr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[434.687460] [076/130] (628s left) kms_ccs (random-ccs-data-4-tiled-mtl-rc-ccs)
[434.788200] Starting subtest: random-ccs-data-4-tiled-mtl-rc-ccs
[434.790535] Starting dynamic subtest: pipe-A-eDP-1
[435.523302] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.724s)
[435.537621] Starting dynamic subtest: pipe-B-eDP-1
[437.338855] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.815s)
[437.347290] Starting dynamic subtest: pipe-C-eDP-1
[439.183401] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.842s)
[439.192245] Starting dynamic subtest: pipe-D-eDP-1
[440.997843] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.813s)
[440.999825] Subtest random-ccs-data-4-tiled-mtl-rc-ccs: SUCCESS (6.199s)
[442.113511] [077/130] (621s left) kms_flip (2x-flip-vs-dpms)
[442.217001] Starting subtest: 2x-flip-vs-dpms
[442.227925] Subtest 2x-flip-vs-dpms: SKIP (0.010s)
[442.328554] [078/130] (621s left) kms_vrr (flip-basic-fastset)
[442.430379] Starting subtest: flip-basic-fastset
[442.437146] Subtest flip-basic-fastset: SKIP (0.000s)
[442.498254] [079/130] (621s left) gem_sync (basic-store-each)
[442.577411] Starting subtest: basic-store-each
[444.942752] Subtest basic-store-each: SUCCESS (2.374s)
[445.117678] [080/130] (618s left) kms_busy (basic-hang)
[445.218215] Starting subtest: basic-hang
[445.220455] Starting dynamic subtest: flip-pipe-A
[445.809942] Dynamic subtest flip-pipe-A: SUCCESS (0.589s)
[445.818059] Starting dynamic subtest: modeset-pipe-A
[449.229181] Dynamic subtest modeset-pipe-A: SUCCESS (3.417s)
[449.238028] Starting dynamic subtest: flip-pipe-D
[450.434523] Dynamic subtest flip-pipe-D: SUCCESS (1.204s)
[450.443547] Starting dynamic subtest: modeset-pipe-D
[453.841227] Dynamic subtest modeset-pipe-D: SUCCESS (3.403s)
[453.843837] Subtest basic-hang: SUCCESS (8.618s)
[454.639007] [081/130] (609s left) gem_mmap_gtt (cpuset-medium-copy-xy)
[454.692820] Subtest cpuset-medium-copy-XY: SKIP (0.000s)
[454.746491] [082/130] (608s left) kms_atomic (plane-invalid-params)
[454.843395] Starting subtest: plane-invalid-params
[454.845697] Starting dynamic subtest: pipe-A-eDP-1
[455.260274] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.415s)
[455.262723] Subtest plane-invalid-params: SUCCESS (0.416s)
[456.067186] [083/130] (607s left) gem_pxp (protected-encrypted-src-copy-not-readible)
[456.197354] Starting subtest: protected-encrypted-src-copy-not-readible
[456.304957] Subtest protected-encrypted-src-copy-not-readible: SUCCESS (0.111s)
[456.448533] [084/130] (607s left) gem_exec_fence (parallel)
[456.627548] Starting subtest: parallel
[456.629991] Starting dynamic subtest: rcs0
[458.774885] Dynamic subtest rcs0: SUCCESS (2.148s)
[458.782599] Starting dynamic subtest: bcs0
[460.928875] Dynamic subtest bcs0: SUCCESS (2.154s)
[460.937939] Starting dynamic subtest: vcs0
[463.080187] Dynamic subtest vcs0: SUCCESS (2.152s)
[463.089291] Starting dynamic subtest: vcs1
[465.231284] Dynamic subtest vcs1: SUCCESS (2.151s)
[465.240310] Starting dynamic subtest: vecs0
[467.384503] Dynamic subtest vecs0: SUCCESS (2.153s)
[467.392761] Starting dynamic subtest: ccs0
[469.539257] Dynamic subtest ccs0: SUCCESS (2.154s)
[469.541291] Subtest parallel: SUCCESS (12.916s)
[470.595799] [085/130] (593s left) core_hotunplug (unbind-rebind)
[470.690612] Starting subtest: unbind-rebind
[476.646766] Subtest unbind-rebind: SUCCESS (5.957s)
[476.678638] [086/130] (587s left) gem_userptr_blits (unsync-unmap-cycles)
[478.577727] Subtest unsync-unmap-cycles: SKIP (0.000s)
[478.704283] [087/130] (585s left) gem_tiling_max_stride
[478.815633] [088/130] (585s left) drm_read (fault-buffer)
[478.992795] Starting subtest: fault-buffer
[478.994981] Subtest fault-buffer: SUCCESS (0.001s)
[479.122389] [089/130] (584s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-render)
[479.229032] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-render
[484.037625] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-render: SUCCESS (4.812s)
[484.199893] [090/130] (579s left) kms_atomic_transition (plane-all-transition-nonblocking-fencing)
[484.302442] Starting subtest: plane-all-transition-nonblocking-fencing
[484.304766] Starting dynamic subtest: pipe-A-eDP-1
[486.807027] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.502s)
[487.094267] Starting dynamic subtest: pipe-B-eDP-1
[490.317054] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.232s)
[490.591818] Subtest plane-all-transition-nonblocking-fencing: SUCCESS (6.289s)
[491.424923] [091/130] (572s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180)
[491.530585] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180
[492.506456] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180: SUCCESS (0.977s)
[492.680982] [092/130] (571s left) kms_pm_dc (dc3co-vpb-simulation)
[493.785322] Starting subtest: dc3co-vpb-simulation
[502.487473] Subtest dc3co-vpb-simulation: SKIP (8.699s)
[502.599277] [093/130] (561s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[502.705580] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc
[502.709683] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[502.802169] [094/130] (561s left) kms_addfb_basic (unused-offsets)
[502.861515] Starting subtest: unused-offsets
[502.863772] Subtest unused-offsets: SUCCESS (0.000s)
[503.009172] [095/130] (561s left) kms_cursor_legacy (cursorb-vs-flipb-varying-size)
[503.122340] Starting subtest: cursorB-vs-flipB-varying-size
[503.126841] Subtest cursorB-vs-flipB-varying-size: SKIP (0.001s)
[503.216212] [096/130] (560s left) gem_userptr_blits (userfault)
[503.286406] Starting subtest: userfault
[503.288644] Subtest userfault: SUCCESS (0.006s)
[503.417985] [097/130] (560s left) gem_ctx_persistence (heartbeat-hostile)
[503.492880] Starting subtest: heartbeat-hostile
[503.497139] Subtest heartbeat-hostile: SKIP (0.000s)
[503.610687] [098/130] (560s left) kms_psr (fbc-psr-sprite-mmap-cpu)
[503.706675] Starting subtest: fbc-psr-sprite-mmap-cpu
[503.708665] Starting dynamic subtest: eDP-1
[503.712303] Dynamic subtest eDP-1: SKIP (0.000s)
[503.715874] Subtest fbc-psr-sprite-mmap-cpu: SKIP (0.001s)
[503.769871] [099/130] (560s left) kms_plane_scaling (planes-downscale-factor-0-25-upscale-20x20)
[503.852801] Starting subtest: planes-downscale-factor-0-25-upscale-20x20
[503.854695] Starting dynamic subtest: pipe-A
[503.870752] Dynamic subtest pipe-A: SKIP (0.011s)
[503.879575] Starting dynamic subtest: pipe-B
[503.891576] Dynamic subtest pipe-B: SKIP (0.020s)
[503.900474] Starting dynamic subtest: pipe-C
[503.913330] Dynamic subtest pipe-C: SKIP (0.024s)
[503.921201] Starting dynamic subtest: pipe-D
[503.936948] Dynamic subtest pipe-D: SKIP (0.024s)
[503.940572] Subtest planes-downscale-factor-0-25-upscale-20x20: SKIP (0.080s)
[504.046242] [100/130] (560s left) syncobj_timeline (wait-for-submit-complex)
[504.110223] Starting subtest: wait-for-submit-complex
[504.172999] Subtest wait-for-submit-complex: SUCCESS (0.062s)
[504.247174] [101/130] (560s left) kms_cursor_edge_walk (128x128-top-edge)
[504.344889] Starting subtest: 128x128-top-edge
[504.349379] Starting dynamic subtest: pipe-A-eDP-1
[507.784768] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.435s)
[507.794658] Starting dynamic subtest: pipe-D-eDP-1
[510.884037] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.095s)
[510.886011] Subtest 128x128-top-edge: SUCCESS (6.535s)
[511.703117] [102/130] (552s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[511.818698] Starting subtest: x-tiled-64bpp-rotate-90
[511.840535] Subtest x-tiled-64bpp-rotate-90: SKIP (0.019s)
[511.971134] [103/130] (552s left) kms_big_fb (linear-64bpp-rotate-180)
[512.080346] Starting subtest: linear-64bpp-rotate-180
[514.381501] Subtest linear-64bpp-rotate-180: SUCCESS (2.300s)
[514.631550] [104/130] (549s left) gem_mmap_gtt (basic-small-copy-xy)
[514.692918] Subtest basic-small-copy-XY: SKIP (0.000s)
[514.745834] [105/130] (549s left) gem_exec_params (invalid-ring2)
[514.803963] Starting subtest: invalid-ring2
[514.806363] Subtest invalid-ring2: SUCCESS (0.000s)
[514.921157] [106/130] (549s left) kms_cursor_crc (cursor-offscreen-512x512)
[515.172913] Starting subtest: cursor-offscreen-512x512
[515.176715] Subtest cursor-offscreen-512x512: SKIP (0.001s)
[515.316104] [107/130] (549s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt)
[515.419784] Starting subtest: psr-1p-primscrn-indfb-msflip-blt
[520.633683] Subtest psr-1p-primscrn-indfb-msflip-blt: SUCCESS (5.215s)
[520.740372] [108/130] (543s left) gem_ccs (block-multicopy-compressed)
[520.806563] Starting subtest: block-multicopy-compressed
[520.811337] Subtest block-multicopy-compressed: SKIP (0.000s)
[520.909601] [109/130] (543s left) gem_ctx_bad_destroy (double-destroy)
[520.963774] Starting subtest: double-destroy
[520.966126] Subtest double-destroy: SUCCESS (0.000s)
[521.031915] [110/130] (543s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-rc-ccs-cc)
[521.136394] Starting subtest: crc-primary-suspend-4-tiled-dg2-rc-ccs-cc
[521.138648] Starting dynamic subtest: pipe-A-eDP-1
[521.147552] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[521.156852] Starting dynamic subtest: pipe-B-eDP-1
[521.165412] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[521.173252] Starting dynamic subtest: pipe-C-eDP-1
[521.181706] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[521.189675] Starting dynamic subtest: pipe-D-eDP-1
[521.198349] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[521.202581] Subtest crc-primary-suspend-4-tiled-dg2-rc-ccs-cc: SKIP (0.004s)
[523.317539] [111/130] (541s left) perf (non-sampling-read-error)
[523.429359] Starting subtest: non-sampling-read-error
[523.444352] Subtest non-sampling-read-error: SUCCESS (0.016s)
[523.484232] [112/130] (541s left) gem_userptr_blits (relocations)
[523.550391] Starting subtest: relocations
[523.554777] Subtest relocations: SKIP (0.001s)
[523.673158] [113/130] (541s left) kms_ccs (crc-primary-basic-4-tiled-dg2-rc-ccs)
[523.774355] Starting subtest: crc-primary-basic-4-tiled-dg2-rc-ccs
[523.776585] Starting dynamic subtest: pipe-A-eDP-1
[523.799576] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[523.809637] Starting dynamic subtest: pipe-B-eDP-1
[523.831286] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[523.839525] Starting dynamic subtest: pipe-C-eDP-1
[523.861342] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[523.869345] Starting dynamic subtest: pipe-D-eDP-1
[523.891587] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[523.895765] Subtest crc-primary-basic-4-tiled-dg2-rc-ccs: SKIP (0.005s)
[525.961376] [114/130] (538s left) kms_color (ctm-0-50)
[526.057384] Starting subtest: ctm-0-50
[526.059819] Starting dynamic subtest: pipe-A-eDP-1
[529.430534] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.372s)
[529.439464] Starting dynamic subtest: pipe-B-eDP-1
[533.615585] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.183s)
[533.624973] Starting dynamic subtest: pipe-C-eDP-1
[537.588280] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.969s)
[537.598332] Starting dynamic subtest: pipe-D-eDP-1
[541.571426] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.981s)
[541.573385] Subtest ctm-0-50: SUCCESS (15.508s)
[542.399698] [115/130] (522s left) gem_exec_schedule (smoketest-all)
[542.473301] Starting subtest: smoketest-all
[574.857828] Subtest smoketest-all: SUCCESS (32.335s)
[575.643329] [116/130] (489s left) kms_psr (fbc-pr-cursor-plane-onoff)
[575.754116] Starting subtest: fbc-pr-cursor-plane-onoff
[575.758384] Subtest fbc-pr-cursor-plane-onoff: SKIP (0.000s)
[575.821457] [117/130] (489s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-plflip-blt)
[575.928626] Starting subtest: fbc-2p-primscrn-shrfb-plflip-blt
[575.932838] Subtest fbc-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[576.032737] [118/130] (488s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-plflip-blt)
[576.140717] Starting subtest: fbcpsr-2p-scndscrn-indfb-plflip-blt
[576.145163] Subtest fbcpsr-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[576.249705] [119/130] (488s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted-signaled)
[576.301157] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[576.303544] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[576.376441] [120/130] (488s left) perf (gen12-mi-rpc)
[576.458377] Starting subtest: gen12-mi-rpc
[576.460748] Starting dynamic subtest: rcs0
[576.485045] Dynamic subtest rcs0: SUCCESS (0.024s)
[576.487421] Subtest gen12-mi-rpc: SUCCESS (0.025s)
[576.520792] [121/130] (488s left) api_intel_allocator (reserve-simple)
[576.602410] Starting subtest: reserve-simple
[576.606442] Subtest reserve-simple: SUCCESS (0.004s)
[576.723845] [122/130] (488s left) gem_render_copy (y-tiled-ccs-to-y-tiled-mc-ccs)
[576.788262] Starting subtest: y-tiled-ccs-to-y-tiled-mc-ccs
[576.792040] Subtest y-tiled-ccs-to-y-tiled-mc-ccs: SKIP (0.000s)
[576.964143] [123/130] (488s left) dumb_buffer (invalid-bpp)
[577.016041] Starting subtest: invalid-bpp
[577.018097] Subtest invalid-bpp: SUCCESS (0.000s)
[577.111348] [124/130] (488s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[577.174003] Starting subtest: single-wait-all-for-submit-available-submitted
[577.178054] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[577.251043] [125/130] (487s left) gem_fenced_exec_thrash (no-spare-fences-busy-interruptible)
[577.321973] Subtest no-spare-fences-busy-interruptible: SKIP (0.000s)
[577.396262] [126/130] (487s left) gem_eio (in-flight-internal-immediate)
[577.651244] Starting subtest: in-flight-internal-immediate
[578.646579] Subtest in-flight-internal-immediate: SUCCESS (0.988s)
[578.804437] [127/130] (486s left) gem_softpin (overlap)
[578.872826] Starting subtest: overlap
[579.736863] Subtest overlap: SUCCESS (0.863s)
[579.864537] [128/130] (485s left) gem_userptr_blits (unsync-overlap)
[579.935850] Subtest unsync-overlap: SKIP (0.000s)
[580.061052] [129/130] (485s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-blt)
[580.165151] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-blt
[580.169154] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[580.249524] [130/130] (485s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[580.360708] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-wc
[580.365153] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[580.467432] Closing watchdogs
Done.