[59.076353] Initializing watchdogs
[59.076416]   /dev/watchdog0
[59.094401] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:03:00.0: 8086:56a0 Intel Dg2 (Gen12) DG2 [Arc A770]
[59.104160] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[59.107678] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.110075] [001/131] (960s left) gem_eio (execbuf)
[59.416085] Starting subtest: execbuf
[59.771351] Subtest execbuf: SUCCESS (0.358s)
[60.002347] [002/131] (959s left) kms_big_fb (linear-16bpp-rotate-270)
[60.212235] Starting subtest: linear-16bpp-rotate-270
[60.269067] Subtest linear-16bpp-rotate-270: SKIP (0.057s)
[60.502075] [003/131] (959s left) gem_ppgtt (blt-vs-render-ctxn)
[60.580231] Starting subtest: blt-vs-render-ctxN
[93.459622] Subtest blt-vs-render-ctxN: SUCCESS (32.907s)
[93.646562] [004/131] (926s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-mmap-wc)
[93.894645] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-mmap-wc
[93.896440] Subtest fbc-1p-offscren-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[94.014315] [005/131] (925s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions)
[94.212556] Starting subtest: short-busy-flip-before-cursor-atomic-transitions
[94.214297] Subtest short-busy-flip-before-cursor-atomic-transitions: SKIP (0.000s)
[94.298264] [006/131] (925s left) gem_userptr_blits (create-destroy-unsync)
[94.385343] Subtest create-destroy-unsync: SKIP (0.000s)
[94.579623] [007/131] (925s left) perf_pmu (busy-accuracy-98)
[94.643613] Starting subtest: busy-accuracy-98
[94.644525] Starting dynamic subtest: rcs0
[97.696134] Dynamic subtest rcs0: SUCCESS (3.051s)
[97.705432] Starting dynamic subtest: bcs0
[100.748501] Dynamic subtest bcs0: SUCCESS (3.052s)
[100.757707] Starting dynamic subtest: vcs0
[103.803168] Dynamic subtest vcs0: SUCCESS (3.055s)
[103.812881] Starting dynamic subtest: vcs1
[106.855337] Dynamic subtest vcs1: SUCCESS (3.052s)
[106.864928] Starting dynamic subtest: vecs0
[109.910439] Dynamic subtest vecs0: SUCCESS (3.055s)
[109.919867] Starting dynamic subtest: vecs1
[112.964669] Dynamic subtest vecs1: SUCCESS (3.054s)
[112.974364] Starting dynamic subtest: ccs0
[116.019431] Dynamic subtest ccs0: SUCCESS (3.054s)
[116.020421] Subtest busy-accuracy-98: SUCCESS (21.374s)
[116.195807] [008/131] (903s left) vgem_basic (busy-fence)
[116.322705] Starting subtest: busy-fence
[116.323633] Subtest busy-fence: SUCCESS (0.000s)
[116.414157] [FACT vgem_basic (busy-fence)] new: kernel.kmod_is_loaded.vgem: true
[116.415942] [009/131] (903s left) kms_scaling_modes (scaling-mode-center)
[116.621165] Starting subtest: scaling-mode-center
[116.626541] Subtest scaling-mode-center: SKIP (0.001s)
[116.729387] [010/131] (903s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted)
[116.812985] Starting subtest: invalid-multi-wait-all-unsubmitted
[116.813936] Subtest invalid-multi-wait-all-unsubmitted: SUCCESS (0.000s)
[116.971571] [011/131] (903s left) kms_flip (busy-flip)
[117.180093] Starting subtest: busy-flip
[117.183692] Starting dynamic subtest: A-DP3
[118.099713] Dynamic subtest A-DP3: SUCCESS (0.915s)
[118.113681] Starting dynamic subtest: B-DP3
[118.877121] Dynamic subtest B-DP3: SUCCESS (0.776s)
[118.891051] Starting dynamic subtest: C-DP3
[119.649654] Dynamic subtest C-DP3: SUCCESS (0.772s)
[119.659595] Starting dynamic subtest: D-DP3
[120.403091] Dynamic subtest D-DP3: SUCCESS (0.752s)
[120.409327] Subtest busy-flip: SUCCESS (3.229s)
[120.789273] [012/131] (899s left) gem_gtt_cpu_tlb
[120.959012] [013/131] (899s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-cpu)
[121.172540] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-cpu
[121.174238] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[121.313679] [014/131] (899s left) kms_psr (psr-sprite-mmap-gtt)
[121.522402] Subtest psr-sprite-mmap-gtt: SKIP (0.000s)
[121.579388] [015/131] (898s left) gem_mmap_gtt (basic-small-bo-tiledy)
[121.661169] Subtest basic-small-bo-tiledY: SKIP (0.000s)
[121.729294] [016/131] (898s left) perf (stress-open-close)
[122.081367] Starting subtest: stress-open-close
[122.082230] Starting dynamic subtest: 0-rcs0
[124.343242] Dynamic subtest 0-rcs0: SUCCESS (2.262s)
[124.344213] Subtest stress-open-close: SUCCESS (2.262s)
[124.403315] [017/131] (896s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[124.613224] Starting subtest: read-crc-frame-sequence
[124.614170] Starting dynamic subtest: pipe-A-DP-3
[125.235068] Dynamic subtest pipe-A-DP-3: SUCCESS (0.620s)
[125.252348] Starting dynamic subtest: pipe-B-DP-3
[125.776409] Dynamic subtest pipe-B-DP-3: SUCCESS (0.541s)
[125.788821] Starting dynamic subtest: pipe-C-DP-3
[126.336674] Dynamic subtest pipe-C-DP-3: SUCCESS (0.558s)
[126.348499] Starting dynamic subtest: pipe-D-DP-3
[126.947186] Dynamic subtest pipe-D-DP-3: SUCCESS (0.609s)
[126.948184] Subtest read-crc-frame-sequence: SUCCESS (2.334s)
[127.211546] [018/131] (893s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[127.420270] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[127.421162] Starting dynamic subtest: pipe-A-valid-mode
[132.686207] Dynamic subtest pipe-A-valid-mode: SUCCESS (5.265s)
[132.687237] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SUCCESS (5.266s)
[132.946367] [019/131] (887s left) gem_busy (close-race)
[133.042332] Starting subtest: close-race
[154.971597] Subtest close-race: SUCCESS (21.928s)
[155.506852] [020/131] (865s left) sriov_basic (enable-vfs-autoprobe-off)
[155.623149] Subtest enable-vfs-autoprobe-off: SKIP (0.000s)
[155.701328] [021/131] (865s left) syncobj_wait (wait-all-interrupted)
[155.787906] Starting subtest: wait-all-interrupted
[155.890666] Subtest wait-all-interrupted: SUCCESS (0.102s)
[155.995751] [022/131] (864s left) syncobj_wait (multi-wait-all-signaled)
[156.070781] Starting subtest: multi-wait-all-signaled
[156.071665] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[156.215528] [023/131] (864s left) kms_psr2_sf (fbc-psr2-overlay-primary-update-sf-dmg-area)
[156.433577] Subtest fbc-psr2-overlay-primary-update-sf-dmg-area: SKIP (0.000s)
[156.484027] [024/131] (864s left) drm_fdinfo (virtual-busy-hang)
[156.556534] Starting subtest: virtual-busy-hang
[156.558357] Subtest virtual-busy-hang: SKIP (0.000s)
[156.641547] [025/131] (864s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-upscaling)
[156.850237] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-upscaling
[156.851152] Starting dynamic subtest: pipe-A-valid-mode
[156.938434] Dynamic subtest pipe-A-valid-mode: SKIP (0.086s)
[156.940440] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-upscaling: SKIP (0.087s)
[157.158229] [026/131] (864s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-32bpp-yftile-downscaling)
[157.367270] Starting subtest: flip-64bpp-yftile-to-32bpp-yftile-downscaling
[157.368186] Starting dynamic subtest: pipe-A-valid-mode
[157.443859] Dynamic subtest pipe-A-valid-mode: SKIP (0.074s)
[157.445687] Subtest flip-64bpp-yftile-to-32bpp-yftile-downscaling: SKIP (0.075s)
[157.659563] [027/131] (863s left) prime_vgem (basic-read)
[157.750594] Starting subtest: basic-read
[157.767286] Subtest basic-read: SKIP (0.016s)
[157.968023] [028/131] (863s left) kms_chamelium_frames (dp-crc-multiple)
[158.259540] Subtest dp-crc-multiple: SKIP (0.000s)
[158.413255] [029/131] (863s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[158.635629] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[158.637442] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[158.759676] [030/131] (862s left) kms_psr (pr-primary-page-flip)
[158.970525] Subtest pr-primary-page-flip: SKIP (0.000s)
[159.033887] [031/131] (862s left) gem_exec_schedule (semaphore-user)
[159.130193] Starting subtest: semaphore-user
[159.205717] Subtest semaphore-user: SUCCESS (0.075s)
[159.596011] [032/131] (862s left) kms_plane_lowres (tiling-yf)
[159.801206] Starting subtest: tiling-yf
[159.803061] Subtest tiling-yf: SKIP (0.000s)
[159.895773] [033/131] (861s left) gem_exec_balancer (parallel-ordering)
[160.269970] Starting subtest: parallel-ordering
[161.720540] Subtest parallel-ordering: SUCCESS (1.451s)
[161.892606] [034/131] (859s left) gem_exec_await (wide-contexts)
[162.146521] Starting subtest: wide-contexts
[183.998451] Subtest wide-contexts: SUCCESS (21.851s)
[184.271324] [035/131] (837s left) kms_vblank (query-busy-hang)
[184.523220] Starting subtest: query-busy-hang
[184.525166] Starting dynamic subtest: pipe-A-DP-3
[210.642196] Dynamic subtest pipe-A-DP-3: SUCCESS (26.115s)
[210.666530] Starting dynamic subtest: pipe-D-DP-3
[235.616638] Dynamic subtest pipe-D-DP-3: SUCCESS (24.966s)
[235.617517] Subtest query-busy-hang: SUCCESS (51.092s)
[235.893680] [036/131] (785s left) gem_eio (in-flight-immediate)
[236.145195] Starting subtest: in-flight-immediate
[240.810470] Subtest in-flight-immediate: SUCCESS (4.666s)
[241.046846] [037/131] (780s left) gem_mmap_offset (partial-remap)
[241.126197] Starting subtest: partial-remap
[241.127130] Starting dynamic subtest: lmem0
[241.129933] Dynamic subtest lmem0: SUCCESS (0.003s)
[241.139800] Starting dynamic subtest: smem0
[241.141900] Dynamic subtest smem0: SUCCESS (0.003s)
[241.142972] Subtest partial-remap: SUCCESS (0.007s)
[241.296850] [038/131] (780s left) syncobj_wait (multi-wait-all-for-submit-signaled)
[241.383284] Starting subtest: multi-wait-all-for-submit-signaled
[241.384213] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.000s)
[241.491850] [039/131] (780s left) kms_cursor_crc (cursor-onscreen-32x10)
[241.783969] Starting subtest: cursor-onscreen-32x10
[241.827173] Subtest cursor-onscreen-32x10: SKIP (0.041s)
[242.195219] [040/131] (779s left) gem_render_copy (x-tiled-to-vebox-yf-tiled)
[242.290767] Starting subtest: x-tiled-to-vebox-yf-tiled
[242.293176] Subtest x-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[242.508737] [041/131] (779s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[242.720807] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-cpu
[242.722502] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[242.867113] [042/131] (779s left) kms_psr2_sf (pr-overlay-primary-update-sf-dmg-area)
[243.079503] Subtest pr-overlay-primary-update-sf-dmg-area: SKIP (0.000s)
[243.129768] [043/131] (779s left) kms_draw_crc (draw-method-blt)
[243.337104] Starting subtest: draw-method-blt
[243.338037] Starting dynamic subtest: xrgb8888-untiled
[243.674410] Dynamic subtest xrgb8888-untiled: SUCCESS (0.337s)
[243.713192] Starting dynamic subtest: rgb565-untiled
[244.007466] Dynamic subtest rgb565-untiled: SUCCESS (0.331s)
[244.045921] Starting dynamic subtest: xrgb2101010-untiled
[244.307405] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.298s)
[244.322256] Starting dynamic subtest: xbgr16161616f-untiled
[244.607431] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.299s)
[244.644945] Starting dynamic subtest: xrgb8888-xtiled
[244.774560] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.165s)
[244.813625] Starting dynamic subtest: rgb565-xtiled
[244.941131] Dynamic subtest rgb565-xtiled: SUCCESS (0.165s)
[244.979315] Starting dynamic subtest: xrgb2101010-xtiled
[245.089559] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.148s)
[245.100876] Starting dynamic subtest: xbgr16161616f-xtiled
[245.207475] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.117s)
[245.244713] Starting dynamic subtest: xrgb8888-4tiled
[245.374427] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.165s)
[245.389335] Starting dynamic subtest: rgb565-4tiled
[245.524477] Dynamic subtest rgb565-4tiled: SUCCESS (0.148s)
[245.563639] Starting dynamic subtest: xrgb2101010-4tiled
[245.674234] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.148s)
[245.711232] Starting dynamic subtest: xbgr16161616f-4tiled
[245.824161] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.148s)
[245.826131] Subtest draw-method-blt: SUCCESS (2.488s)
[245.989653] [044/131] (776s left) kms_frontbuffer_tracking (psr-shrfb-scaledprimary)
[246.202896] Starting subtest: psr-shrfb-scaledprimary
[246.204564] Subtest psr-shrfb-scaledprimary: SKIP (0.000s)
[246.355306] [045/131] (776s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-blt)
[246.568830] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-blt
[251.476997] Subtest fbc-1p-primscrn-pri-shrfb-draw-blt: FAIL (4.513s)
[251.547632] [046/131] (770s left) kms_getfb (getfb2-into-addfb2)
[251.637210] Starting subtest: getfb2-into-addfb2
[251.638142] Subtest getfb2-into-addfb2: SUCCESS (0.000s)
[251.802675] [047/131] (770s left) gem_exec_params (batch-first)
[251.886063] Starting subtest: batch-first
[251.913133] Subtest batch-first: SUCCESS (0.027s)
[252.109812] [048/131] (770s left) kms_big_fb (y-tiled-64bpp-rotate-0)
[252.328617] Starting subtest: y-tiled-64bpp-rotate-0
[252.330441] Subtest y-tiled-64bpp-rotate-0: SKIP (0.000s)
[252.460443] [049/131] (770s left) kms_big_fb (x-tiled-64bpp-rotate-180)
[252.683427] Starting subtest: x-tiled-64bpp-rotate-180
[254.558577] Subtest x-tiled-64bpp-rotate-180: SUCCESS (1.874s)
[254.728641] [050/131] (768s left) gem_exec_flush (basic-uc-pro-default)
[254.815164] Subtest basic-uc-pro-default: SKIP (0.000s)
[254.880366] [051/131] (767s left) kms_ccs (bad-rotation-90-4-tiled-dg2-mc-ccs)
[255.089279] Starting subtest: bad-rotation-90-4-tiled-dg2-mc-ccs
[255.090228] Starting dynamic subtest: pipe-A-DP-3
[258.117968] Dynamic subtest pipe-A-DP-3: SUCCESS (3.027s)
[258.128191] Starting dynamic subtest: pipe-B-DP-3
[258.136208] Dynamic subtest pipe-B-DP-3: SUCCESS (0.003s)
[258.146486] Starting dynamic subtest: pipe-C-DP-3
[258.154907] Dynamic subtest pipe-C-DP-3: SUCCESS (0.006s)
[258.165317] Starting dynamic subtest: pipe-D-DP-3
[258.173143] Dynamic subtest pipe-D-DP-3: SUCCESS (0.007s)
[258.174070] Subtest bad-rotation-90-4-tiled-dg2-mc-ccs: SUCCESS (3.048s)
[258.649317] [052/131] (764s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[258.867836] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[258.870164] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[258.980380] [053/131] (764s left) kms_force_connector_basic (force-connector-state)
[259.095426] Starting subtest: force-connector-state
[259.476081] Subtest force-connector-state: SUCCESS (0.381s)
[259.917954] [054/131] (763s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-onoff)
[260.130953] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-onoff
[260.132637] Subtest fbcpsr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[260.274827] [055/131] (762s left) kms_psr (fbc-psr2-dpms)
[260.485398] Subtest fbc-psr2-dpms: SKIP (0.000s)
[260.538659] [056/131] (762s left) gem_exec_flush (basic-uc-rw-default)
[260.623275] Subtest basic-uc-rw-default: SKIP (0.000s)
[260.688374] [057/131] (762s left) kms_chamelium_hpd (vga-hpd)
[260.973737] Subtest vga-hpd: SKIP (0.000s)
[261.125435] [058/131] (762s left) gem_softpin (evict-snoop)
[261.213318] Starting subtest: evict-snoop
[261.215575] Subtest evict-snoop: SKIP (0.000s)
[261.371506] [059/131] (761s left) kms_cursor_crc (cursor-rapid-movement-512x512)
[261.782645] Starting subtest: cursor-rapid-movement-512x512
[261.784445] Subtest cursor-rapid-movement-512x512: SKIP (0.001s)
[262.026706] [060/131] (761s left) gem_softpin (overlap)
[262.111218] Starting subtest: overlap
[264.585258] Subtest overlap: SUCCESS (2.474s)
[264.705235] [061/131] (758s left) gem_exec_whisper (basic-queues-priority)
[264.798463] Starting subtest: basic-queues-priority
[286.971673] Subtest basic-queues-priority: SUCCESS (22.170s)
[287.201944] [062/131] (736s left) i915_hangman (engine-error-state-capture)
[287.297310] Starting subtest: engine-error-state-capture
[287.298200] Starting dynamic subtest: rcs0
[289.603940] Dynamic subtest rcs0: SUCCESS (2.306s)
[289.614792] Starting dynamic subtest: bcs0
[293.580511] Dynamic subtest bcs0: SUCCESS (3.976s)
[293.593273] Starting dynamic subtest: vcs0
[297.597161] Dynamic subtest vcs0: SUCCESS (4.016s)
[297.607810] Starting dynamic subtest: vcs1
[301.545494] Dynamic subtest vcs1: SUCCESS (3.948s)
[301.556288] Starting dynamic subtest: vecs0
[305.618636] Dynamic subtest vecs0: SUCCESS (4.072s)
[305.632388] Starting dynamic subtest: vecs1
[309.551288] Dynamic subtest vecs1: SUCCESS (3.931s)
[309.562367] Starting dynamic subtest: ccs0
[313.597398] Dynamic subtest ccs0: SUCCESS (4.045s)
[313.598375] Subtest engine-error-state-capture: SUCCESS (26.299s)
[313.723240] [063/131] (709s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-blt)
[313.941057] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-blt
[316.476520] Subtest fbc-1p-primscrn-spr-indfb-draw-blt: SUCCESS (2.537s)
[316.691937] [064/131] (706s left) gem_pxp (fail-invalid-protected-context)
[319.298386] Starting subtest: fail-invalid-protected-context
[319.300985] Subtest fail-invalid-protected-context: SKIP (0.000s)
[319.396486] [065/131] (704s left) kms_chamelium_hpd (dp-hpd-with-enabled-mode)
[319.685379] Subtest dp-hpd-with-enabled-mode: SKIP (0.000s)
[319.841645] [066/131] (703s left) syncobj_wait (multi-wait-for-submit-submitted)
[319.921822] Starting subtest: multi-wait-for-submit-submitted
[320.024457] Subtest multi-wait-for-submit-submitted: SUCCESS (0.102s)
[320.170927] [067/131] (703s left) gem_exec_balancer (sequential)
[320.263637] Starting subtest: sequential
[325.970493] Subtest sequential: SUCCESS (5.706s)
[326.321156] [068/131] (697s left) kms_pm_rpm (modeset-lpsp-stress-no-wait)
[328.052718] Starting subtest: modeset-lpsp-stress-no-wait
[328.275100] Subtest modeset-lpsp-stress-no-wait: SKIP (0.223s)
[328.484911] [069/131] (695s left) kms_psr (pr-sprite-mmap-cpu)
[328.689422] Subtest pr-sprite-mmap-cpu: SKIP (0.000s)
[328.754007] [070/131] (695s left) device_reset (unbind-reset-rebind)
[328.836302] Starting subtest: unbind-reset-rebind
[331.491330] Subtest unbind-reset-rebind: SUCCESS (2.654s)
[331.548871] [071/131] (692s left) syncobj_timeline (etime-multi-wait-for-submit-unsubmitted-submitted)
[331.627761] Starting subtest: etime-multi-wait-for-submit-unsubmitted-submitted
[331.737555] Subtest etime-multi-wait-for-submit-unsubmitted-submitted: SUCCESS (0.108s)
[331.859566] [072/131] (692s left) gem_ctx_engines (invalid-engines)
[331.953962] Starting subtest: invalid-engines
[331.982479] Subtest invalid-engines: SUCCESS (0.029s)
[332.275920] [073/131] (691s left) gem_exec_schedule (noreorder-priority)
[332.523324] Starting subtest: noreorder-priority
[332.524843] Starting dynamic subtest: rcs0
[333.718597] Dynamic subtest rcs0: SUCCESS (1.195s)
[333.732503] Starting dynamic subtest: bcs0
[334.861394] Dynamic subtest bcs0: SUCCESS (1.142s)
[334.873951] Starting dynamic subtest: vcs0
[335.999194] Dynamic subtest vcs0: SUCCESS (1.137s)
[336.011723] Starting dynamic subtest: vcs1
[337.127381] Dynamic subtest vcs1: SUCCESS (1.127s)
[337.139166] Starting dynamic subtest: vecs0
[338.248233] Dynamic subtest vecs0: SUCCESS (1.120s)
[338.259822] Starting dynamic subtest: vecs1
[339.378250] Dynamic subtest vecs1: SUCCESS (1.129s)
[339.390528] Starting dynamic subtest: ccs0
[340.497244] Dynamic subtest ccs0: SUCCESS (1.118s)
[340.498616] Subtest noreorder-priority: SUCCESS (7.975s)
[340.737808] [074/131] (683s left) i915_pm_rps (thresholds)
[340.822331] Starting subtest: thresholds
[340.824243] Subtest thresholds: SKIP (0.001s)
[340.970865] [075/131] (683s left) gem_userptr_blits (set-cache-level)
[341.061064] Starting subtest: set-cache-level
[341.061988] Subtest set-cache-level: SUCCESS (0.000s)
[341.209853] [076/131] (683s left) gem_render_copy (y-tiled-ccs-to-yf-tiled-mc-ccs)
[341.296460] Starting subtest: y-tiled-ccs-to-yf-tiled-mc-ccs
[341.298601] Subtest y-tiled-ccs-to-yf-tiled-mc-ccs: SKIP (0.000s)
[341.556522] [077/131] (682s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[341.768935] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[341.770680] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[341.909997] [078/131] (682s left) kms_big_fb (yf-tiled-16bpp-rotate-0)
[342.127514] Starting subtest: yf-tiled-16bpp-rotate-0
[342.129332] Subtest yf-tiled-16bpp-rotate-0: SKIP (0.000s)
[342.261511] [079/131] (682s left) fbdev (unaligned-write)
[342.322469] Starting subtest: unaligned-write
[342.638415] Subtest unaligned-write: SUCCESS (0.315s)
[342.719995] [080/131] (681s left) kms_setmode (basic-clone-single-crtc)
[342.803237] Starting subtest: basic-clone-single-crtc
[342.806146] Subtest basic-clone-single-crtc: SKIP (0.000s)
[342.968681] [081/131] (681s left) kms_psr2_sf (fbc-pr-cursor-plane-update-sf)
[343.182621] Subtest fbc-pr-cursor-plane-update-sf: SKIP (0.000s)
[343.229730] [082/131] (681s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[343.443012] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt
[343.444815] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[343.577386] [083/131] (681s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-mmap-gtt)
[343.791790] Starting subtest: psr-1p-offscren-pri-shrfb-draw-mmap-gtt
[343.793497] Subtest psr-1p-offscren-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[343.922437] [084/131] (681s left) syncobj_timeline (invalid-transfer-bad-pad)
[344.005734] Starting subtest: invalid-transfer-bad-pad
[344.006645] Subtest invalid-transfer-bad-pad: SUCCESS (0.000s)
[344.175421] [085/131] (680s left) gem_vm_create (isolation)
[344.266474] Starting subtest: isolation
[344.277133] Subtest isolation: SUCCESS (0.011s)
[344.474628] [086/131] (680s left) kms_big_fb (4-tiled-addfb)
[344.690663] Starting subtest: 4-tiled-addfb
[344.691525] Subtest 4-tiled-addfb: SUCCESS (0.000s)
[344.821395] [087/131] (680s left) syncobj_wait (invalid-single-wait-all-unsubmitted)
[344.903686] Starting subtest: invalid-single-wait-all-unsubmitted
[344.904596] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[345.060177] [088/131] (680s left) gem_exec_fence (syncobj-stationary-timeline-chain-engines)
[345.704271] Starting subtest: syncobj-stationary-timeline-chain-engines
[345.846260] Subtest syncobj-stationary-timeline-chain-engines: SUCCESS (0.142s)
[346.139621] [089/131] (679s left) kms_properties (plane-properties-legacy)
[346.353432] Starting subtest: plane-properties-legacy
[346.354441] Starting dynamic subtest: pipe-A-DP-3
[346.832642] Dynamic subtest pipe-A-DP-3: SUCCESS (0.477s)
[346.852731] Starting dynamic subtest: pipe-B-DP-3
[347.346300] Dynamic subtest pipe-B-DP-3: SUCCESS (0.513s)
[347.360577] Starting dynamic subtest: pipe-C-DP-3
[347.852423] Dynamic subtest pipe-C-DP-3: SUCCESS (0.505s)
[347.865293] Starting dynamic subtest: pipe-D-DP-3
[348.357432] Dynamic subtest pipe-D-DP-3: SUCCESS (0.504s)
[348.358404] Subtest plane-properties-legacy: SUCCESS (2.004s)
[348.630921] [090/131] (676s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[348.845405] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[348.847193] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[348.973072] [091/131] (676s left) syncobj_timeline (wait-all-interrupted)
[349.053781] Starting subtest: wait-all-interrupted
[349.156371] Subtest wait-all-interrupted: SUCCESS (0.102s)
[349.279464] [092/131] (676s left) syncobj_timeline (reset-during-wait-for-submit)
[349.366041] Starting subtest: reset-during-wait-for-submit
[349.470207] Subtest reset-during-wait-for-submit: SUCCESS (0.103s)
[349.586640] [093/131] (675s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-pwrite)
[349.800033] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-pwrite
[349.801799] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[349.963638] [094/131] (675s left) gem_exec_schedule (in-order)
[350.058719] Starting subtest: in-order
[350.059662] Starting dynamic subtest: rcs0
[350.328457] Dynamic subtest rcs0: SUCCESS (0.269s)
[350.340245] Starting dynamic subtest: bcs0
[350.519589] Dynamic subtest bcs0: SUCCESS (0.190s)
[350.531123] Starting dynamic subtest: vcs0
[350.721197] Dynamic subtest vcs0: SUCCESS (0.201s)
[350.732765] Starting dynamic subtest: vcs1
[350.926276] Dynamic subtest vcs1: SUCCESS (0.204s)
[350.938075] Starting dynamic subtest: vecs0
[351.129072] Dynamic subtest vecs0: SUCCESS (0.202s)
[351.140707] Starting dynamic subtest: vecs1
[351.328716] Dynamic subtest vecs1: SUCCESS (0.199s)
[351.340436] Starting dynamic subtest: ccs0
[351.529688] Dynamic subtest ccs0: SUCCESS (0.200s)
[351.530682] Subtest in-order: SUCCESS (1.471s)
[352.069299] [095/131] (673s left) kms_ccs (crc-primary-suspend-yf-tiled-ccs)
[352.270442] Starting subtest: crc-primary-suspend-yf-tiled-ccs
[352.271439] Starting dynamic subtest: pipe-A-DP-3
[352.274913] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[352.286499] Starting dynamic subtest: pipe-B-DP-3
[352.290103] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[352.301379] Starting dynamic subtest: pipe-C-DP-3
[352.304912] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[352.315922] Starting dynamic subtest: pipe-D-DP-3
[352.319320] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[352.321067] Subtest crc-primary-suspend-yf-tiled-ccs: SKIP (0.006s)
[352.713910] [096/131] (673s left) gem_madvise (dontneed-before-pwrite)
[352.765641] Starting subtest: dontneed-before-pwrite
[352.794143] Subtest dontneed-before-pwrite: SKIP (0.027s)
[352.910803] [097/131] (672s left) kms_vrr (seamless-rr-switch-drrs)
[353.117341] Starting subtest: seamless-rr-switch-drrs
[353.120077] Subtest seamless-rr-switch-drrs: SKIP (0.000s)
[353.211924] [098/131] (672s left) gem_ctx_persistence (userptr)
[353.310776] Starting subtest: userptr
[353.522384] Subtest userptr: SUCCESS (0.211s)
[353.674648] [099/131] (672s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-pwrite)
[353.886683] Starting subtest: psr-2p-primscrn-pri-indfb-draw-pwrite
[353.888485] Subtest psr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[354.054609] [100/131] (672s left) kms_addfb_basic (legacy-format)
[354.134238] Starting subtest: legacy-format
[354.164414] Subtest legacy-format: SUCCESS (0.028s)
[354.409928] [101/131] (671s left) kms_ccs (random-ccs-data-4-tiled-mtl-rc-ccs)
[354.621496] Starting subtest: random-ccs-data-4-tiled-mtl-rc-ccs
[354.623675] Starting dynamic subtest: pipe-A-DP-3
[354.633141] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[354.644501] Starting dynamic subtest: pipe-B-DP-3
[354.653771] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[354.665153] Starting dynamic subtest: pipe-C-DP-3
[354.674364] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[354.685708] Starting dynamic subtest: pipe-D-DP-3
[354.695258] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[354.696969] Subtest random-ccs-data-4-tiled-mtl-rc-ccs: SKIP (0.006s)
[354.996798] [102/131] (671s left) sw_sync (sync_busy_fork_unixsocket)
[355.057771] Starting subtest: sync_busy_fork_unixsocket
[355.067826] Subtest sync_busy_fork_unixsocket: SUCCESS (0.011s)
[355.147018] [103/131] (671s left) kms_frontbuffer_tracking (fbcpsr-2p-pri-indfb-multidraw)
[355.359830] Starting subtest: fbcpsr-2p-pri-indfb-multidraw
[355.361544] Subtest fbcpsr-2p-pri-indfb-multidraw: SKIP (0.000s)
[355.464558] [104/131] (670s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-msflip-blt)
[355.675973] Starting subtest: fbc-2p-scndscrn-shrfb-msflip-blt
[355.677792] Subtest fbc-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[355.780775] [105/131] (670s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-blt)
[355.996169] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-blt
[355.997926] Subtest psr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[356.116098] [106/131] (670s left) kms_psr (fbc-pr-sprite-blt)
[356.322406] Subtest fbc-pr-sprite-blt: SKIP (0.000s)
[356.375210] [107/131] (670s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[356.583733] Starting subtest: addfb25-y-tiled-small-legacy
[356.585444] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[356.717069] [108/131] (669s left) api_intel_bb (simple-bb)
[356.793361] Starting subtest: simple-bb
[356.819430] Subtest simple-bb: SUCCESS (0.026s)
[357.013349] [109/131] (669s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-mc-ccs)
[357.219505] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-mc-ccs
[357.221772] Starting dynamic subtest: pipe-A-DP-3
[357.230384] Dynamic subtest pipe-A-DP-3: SKIP (0.001s)
[357.241645] Starting dynamic subtest: pipe-B-DP-3
[357.250212] Dynamic subtest pipe-B-DP-3: SKIP (0.001s)
[357.261373] Starting dynamic subtest: pipe-C-DP-3
[357.269808] Dynamic subtest pipe-C-DP-3: SKIP (0.001s)
[357.281020] Starting dynamic subtest: pipe-D-DP-3
[357.291160] Dynamic subtest pipe-D-DP-3: SKIP (0.001s)
[357.293187] Subtest crc-primary-rotation-180-y-tiled-gen12-mc-ccs: SKIP (0.008s)
[357.678903] [110/131] (668s left) gem_exec_balancer (bonded-chain)
[357.768930] Starting subtest: bonded-chain
[371.295457] Subtest bonded-chain: SUCCESS (13.526s)
[371.711394] [111/131] (655s left) kms_invalid_mode (overflow-vrefresh)
[371.920166] Starting subtest: overflow-vrefresh
[371.921165] Starting dynamic subtest: pipe-A-DP-3
[371.998239] Dynamic subtest pipe-A-DP-3: SUCCESS (0.077s)
[372.013934] Starting dynamic subtest: pipe-B-DP-3
[372.015070] Dynamic subtest pipe-B-DP-3: SUCCESS (0.001s)
[372.028817] Starting dynamic subtest: pipe-C-DP-3
[372.029772] Dynamic subtest pipe-C-DP-3: SUCCESS (0.001s)
[372.043512] Starting dynamic subtest: pipe-D-DP-3
[372.044475] Dynamic subtest pipe-D-DP-3: SUCCESS (0.001s)
[372.045424] Subtest overflow-vrefresh: SUCCESS (0.087s)
[372.239206] [112/131] (654s left) kms_rotation_crc (primary-yf-tiled-reflect-x-90)
[372.445504] Starting subtest: primary-yf-tiled-reflect-x-90
[372.447340] Subtest primary-yf-tiled-reflect-x-90: SKIP (0.001s)
[372.554746] [113/131] (654s left) syncobj_wait (invalid-reset-illegal-handle)
[372.636977] Starting subtest: invalid-reset-illegal-handle
[372.638189] Subtest invalid-reset-illegal-handle: SUCCESS (0.000s)
[372.766997] [114/131] (654s left) kms_ccs (bad-rotation-90-y-tiled-gen12-rc-ccs-cc)
[372.982474] Starting subtest: bad-rotation-90-y-tiled-gen12-rc-ccs-cc
[372.984741] Starting dynamic subtest: pipe-A-DP-3
[372.993416] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[373.004839] Starting dynamic subtest: pipe-B-DP-3
[373.013547] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[373.024709] Starting dynamic subtest: pipe-C-DP-3
[373.033196] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[373.044534] Starting dynamic subtest: pipe-D-DP-3
[373.055176] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[373.057417] Subtest bad-rotation-90-y-tiled-gen12-rc-ccs-cc: SKIP (0.006s)
[373.433585] [115/131] (653s left) kms_rotation_crc (primary-rotation-180)
[373.639487] Starting subtest: primary-rotation-180
[384.260983] Subtest primary-rotation-180: SUCCESS (10.620s)
[384.631437] [116/131] (642s left) syncobj_wait (wait-for-submit-snapshot)
[384.726857] Starting subtest: wait-for-submit-snapshot
[384.811118] Subtest wait-for-submit-snapshot: SUCCESS (0.083s)
[384.954118] [117/131] (642s left) gem_exec_fence (basic-busy-all)
[385.045934] Starting subtest: basic-busy-all
[385.154312] Subtest basic-busy-all: SUCCESS (0.108s)
[385.978569] [118/131] (641s left) gem_ctx_isolation (preservation-s3)
[386.067691] Starting subtest: preservation-S3
[386.068623] Starting dynamic subtest: rcs0
[405.784305] Dynamic subtest rcs0: SUCCESS (4.228s)
[405.797979] Starting dynamic subtest: bcs0
[425.741072] Dynamic subtest bcs0: SUCCESS (3.947s)
[425.754932] Starting dynamic subtest: vcs0
[445.189499] Dynamic subtest vcs0: SUCCESS (3.913s)
[445.203079] Starting dynamic subtest: vcs1
[464.685823] Dynamic subtest vcs1: SUCCESS (3.907s)
[464.699714] Starting dynamic subtest: vecs0
[484.648620] Dynamic subtest vecs0: SUCCESS (3.816s)
[484.662285] Starting dynamic subtest: vecs1
[504.148668] Dynamic subtest vecs1: SUCCESS (4.009s)
[504.162314] Starting dynamic subtest: ccs0
[523.574043] Dynamic subtest ccs0: SUCCESS (3.875s)
[523.574969] Subtest preservation-S3: SUCCESS (27.702s)
[524.895245] [119/131] (613s left) kms_plane_scaling (planes-upscale-20x20)
[525.111926] Starting subtest: planes-upscale-20x20
[525.112788] Starting dynamic subtest: pipe-A
[525.224439] Dynamic subtest pipe-A: SUCCESS (0.112s)
[525.244435] Starting dynamic subtest: pipe-B
[525.425835] Dynamic subtest pipe-B: SUCCESS (0.200s)
[525.451023] Starting dynamic subtest: pipe-C
[525.645795] Dynamic subtest pipe-C: SUCCESS (0.219s)
[525.670264] Starting dynamic subtest: pipe-D
[525.866071] Dynamic subtest pipe-D: SUCCESS (0.219s)
[525.867150] Subtest planes-upscale-20x20: SUCCESS (0.755s)
[526.192676] [120/131] (612s left) kms_flip (2x-blocking-wf_vblank)
[526.404380] Starting subtest: 2x-blocking-wf_vblank
[526.419036] Subtest 2x-blocking-wf_vblank: SKIP (0.014s)
[526.522153] [121/131] (611s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-pgflip-blt)
[526.733472] Starting subtest: fbcpsr-2p-primscrn-shrfb-pgflip-blt
[526.735289] Subtest fbcpsr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[526.876533] [122/131] (611s left) kms_flip (2x-dpms-vs-vblank-race)
[527.083446] Starting subtest: 2x-dpms-vs-vblank-race
[527.098095] Subtest 2x-dpms-vs-vblank-race: SKIP (0.014s)
[527.206516] [123/131] (611s left) kms_content_protection (dp-mst-lic-type-0)
[527.470628] Starting subtest: dp-mst-lic-type-0
[527.472591] Subtest dp-mst-lic-type-0: SKIP (0.000s)
[527.681820] [124/131] (610s left) api_intel_allocator (reopen)
[527.762796] Starting subtest: reopen
[527.784962] Subtest reopen: SUCCESS (0.022s)
[527.957124] [125/131] (610s left) prime_self_import (basic-with_one_bo_two_files)
[528.010598] Starting subtest: basic-with_one_bo_two_files
[528.053807] Subtest basic-with_one_bo_two_files: SUCCESS (0.044s)
[528.175882] [126/131] (610s left) gen9_exec_parse (bb-start-cmd)
[528.259862] Subtest bb-start-cmd: SKIP (0.000s)
[528.351003] [127/131] (610s left) gem_lmem_evict (dontneed-evict-race)
[531.243265] Starting subtest: dontneed-evict-race
[533.358889] Subtest dontneed-evict-race: SUCCESS (2.117s)
[534.651414] [FACT gem_lmem_evict (dontneed-evict-race)] deleted: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[534.652514] [FACT gem_lmem_evict (dontneed-evict-race)] deleted: kernel.kmod_is_loaded.i915: true
[534.654615] [128/131] (604s left) gem_fence_thrash (bo-write-verify-threaded-none)
[534.697488] Starting subtest: bo-write-verify-threaded-none
[536.149162] Subtest bo-write-verify-threaded-none: SKIP (1.451s)
[536.238786] [FACT gem_fence_thrash (bo-write-verify-threaded-none)] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[536.244112] [FACT gem_fence_thrash (bo-write-verify-threaded-none)] new: kernel.kmod_is_loaded.i915: true
[536.248116] [129/131] (602s left) kms_lease (lessee-list)
[536.473969] Starting subtest: lessee-list
[536.476388] Starting dynamic subtest: pipe-A-DP-3
[536.477285] Dynamic subtest pipe-A-DP-3: SUCCESS (0.002s)
[536.489852] Starting dynamic subtest: pipe-B-DP-3
[536.490702] Dynamic subtest pipe-B-DP-3: SUCCESS (0.001s)
[536.501551] Starting dynamic subtest: pipe-C-DP-3
[536.502632] Dynamic subtest pipe-C-DP-3: SUCCESS (0.001s)
[536.512915] Starting dynamic subtest: pipe-D-DP-3
[536.513911] Dynamic subtest pipe-D-DP-3: SUCCESS (0.002s)
[536.514846] Subtest lessee-list: SUCCESS (0.013s)
[536.606704] [130/131] (602s left) kms_plane_scaling (plane-upscale-factor-0-25-with-rotation)
[536.811945] Starting subtest: plane-upscale-factor-0-25-with-rotation
[536.812859] Starting dynamic subtest: pipe-A
[537.184659] Dynamic subtest pipe-A: SUCCESS (0.372s)
[537.214818] Starting dynamic subtest: pipe-B
[537.670839] Dynamic subtest pipe-B: SUCCESS (0.484s)
[537.699281] Starting dynamic subtest: pipe-C
[538.156794] Dynamic subtest pipe-C: SUCCESS (0.484s)
[538.183783] Starting dynamic subtest: pipe-D
[538.641877] Dynamic subtest pipe-D: SUCCESS (0.483s)
[538.643216] Subtest plane-upscale-factor-0-25-with-rotation: SUCCESS (1.831s)
[538.978223] [131/131] (599s left) gem_mmap_gtt (cpuset-big-copy)
[539.059783] Subtest cpuset-big-copy: SKIP (0.000s)
[539.140288] Closing watchdogs
Done.