[44.347897] Initializing watchdogs
[44.347993]   /dev/watchdog0
[44.356855] [001/144] (960s left) kms_chamelium_color (ctm-red-to-blue)
[44.463047] Subtest ctm-red-to-blue: SKIP (0.000s)
[44.500881] [002/144] (960s left) kms_ccs (pipe-a-random-ccs-data-4-tiled-dg2-rc-ccs-cc)
[44.880618] Starting subtest: pipe-A-random-ccs-data-4-tiled-dg2-rc-ccs-cc
[44.887804] Subtest pipe-A-random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.000s)
[45.822524] [003/144] (959s left) gem_mmap_wc (write-wc-read-gtt)
[45.887231] Subtest write-wc-read-gtt: SKIP (0.000s)
[45.911688] [004/144] (959s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions)
[46.032562] Starting subtest: short-flip-after-cursor-atomic-transitions
[47.130762] Subtest short-flip-after-cursor-atomic-transitions: SUCCESS (1.096s)
[48.046794] [005/144] (956s left) vc4/vc4_perfmon (create-single-perfmon)
[48.125692] Subtest create-single-perfmon: SKIP (0.000s)
[48.168914] [006/144] (956s left) prime_udl
[48.254885] [007/144] (956s left) syncobj_timeline (invalid-wait-illegal-handle)
[48.318981] Starting subtest: invalid-wait-illegal-handle
[48.321430] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[48.396630] [008/144] (956s left) gem_mmap_wc (coherency)
[48.457066] Subtest coherency: SKIP (0.000s)
[48.506227] [009/144] (956s left) kms_ccs (pipe-b-crc-sprite-planes-basic-4-tiled-mtl-rc-ccs)
[48.941772] Starting subtest: pipe-B-crc-sprite-planes-basic-4-tiled-mtl-rc-ccs
[50.930369] Subtest pipe-B-crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SUCCESS (1.989s)
[52.159459] [010/144] (952s left) core_auth (basic-auth)
[52.247163] Starting subtest: basic-auth
[52.249701] Subtest basic-auth: SUCCESS (0.002s)
[52.344266] [011/144] (952s left) gem_eio (in-flight-suspend)
[52.543099] Starting subtest: in-flight-suspend
[96.342940] Subtest in-flight-suspend: SUCCESS (13.806s)
[96.451787] [012/144] (938s left) kms_lease (lease-get)
[96.521930] Starting subtest: lease-get
[96.524629] Starting dynamic subtest: pipe-A-eDP-1
[96.527440] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.002s)
[96.541263] Starting dynamic subtest: pipe-B-eDP-1
[96.543854] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.002s)
[96.558356] Starting dynamic subtest: pipe-C-eDP-1
[96.561180] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[96.572477] Starting dynamic subtest: pipe-D-eDP-1
[96.574827] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[96.577094] Subtest lease-get: SUCCESS (0.014s)
[96.627863] [013/144] (938s left) gem_exec_reloc (basic-cpu-active)
[96.701328] Subtest basic-cpu-active: SKIP (0.000s)
[96.761723] [014/144] (938s left) kms_rotation_crc (multiplane-rotation)
[96.857371] Starting subtest: multiplane-rotation
[105.003406] Subtest multiplane-rotation: SUCCESS (8.148s)
[105.090618] [015/144] (930s left) kms_draw_crc (fill-fb)
[105.200001] Starting subtest: fill-fb
[105.653001] Subtest fill-fb: SUCCESS (0.455s)
[105.747041] [016/144] (929s left) kms_multipipe_modeset (basic-max-pipe-crc-check)
[105.814185] Starting subtest: basic-max-pipe-crc-check
[105.818534] Subtest basic-max-pipe-crc-check: SKIP (0.000s)
[105.844737] [017/144] (929s left) kms_pm_rpm (system-suspend-modeset)
[107.587598] Starting subtest: system-suspend-modeset
[131.395689] Subtest system-suspend-modeset: SUCCESS (9.219s)
[132.182789] [018/144] (917s left) kms_setmode (invalid-clone-single-crtc-stealing)
[132.256383] Starting subtest: invalid-clone-single-crtc-stealing
[132.264345] Subtest invalid-clone-single-crtc-stealing: SKIP (0.001s)
[132.351145] [019/144] (917s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-render)
[132.446523] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-render
[132.450991] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[132.546119] [020/144] (917s left) kms_ccs (pipe-a-ccs-on-another-bo-y-tiled-gen12-rc-ccs)
[132.925170] Starting subtest: pipe-A-ccs-on-another-bo-y-tiled-gen12-rc-ccs
[132.930358] Subtest pipe-A-ccs-on-another-bo-y-tiled-gen12-rc-ccs: SKIP (0.000s)
[133.849550] [021/144] (916s left) kms_ccs (pipe-c-crc-primary-basic-4-tiled-mtl-rc-ccs)
[134.267376] Starting subtest: pipe-C-crc-primary-basic-4-tiled-mtl-rc-ccs
[135.287464] Subtest pipe-C-crc-primary-basic-4-tiled-mtl-rc-ccs: SUCCESS (1.015s)
[136.458373] [022/144] (913s left) gem_ctx_bad_destroy (invalid-default-ctx)
[136.523832] Starting subtest: invalid-default-ctx
[136.526514] Subtest invalid-default-ctx: SUCCESS (0.000s)
[136.593068] [023/144] (913s left) kms_plane_scaling (planes-downscale-factor-0-5-upscale-factor-0-25)
[136.682077] Starting subtest: planes-downscale-factor-0-5-upscale-factor-0-25
[136.684578] Starting dynamic subtest: pipe-A-eDP-1
[136.689381] Dynamic subtest pipe-A-eDP-1: SKIP (0.003s)
[136.706531] Starting dynamic subtest: pipe-B-eDP-1
[136.712594] Dynamic subtest pipe-B-eDP-1: SKIP (0.011s)
[136.728372] Starting dynamic subtest: pipe-C-eDP-1
[136.733283] Dynamic subtest pipe-C-eDP-1: SKIP (0.012s)
[136.746740] Starting dynamic subtest: pipe-D-eDP-1
[136.751721] Dynamic subtest pipe-D-eDP-1: SKIP (0.012s)
[136.756515] Subtest planes-downscale-factor-0-5-upscale-factor-0-25: SKIP (0.041s)
[136.813200] [024/144] (913s left) api_intel_allocator (fork-simple-stress-signal)
[136.880030] Starting subtest: fork-simple-stress-signal
[142.280604] Subtest fork-simple-stress-signal: SUCCESS (5.403s)
[142.376565] [025/144] (907s left) gem_exec_parallel (basic)
[142.454706] Starting subtest: basic
[142.457359] Starting dynamic subtest: rcs0
[144.713022] Dynamic subtest rcs0: SUCCESS (2.257s)
[144.730693] Starting dynamic subtest: bcs0
[146.913762] Dynamic subtest bcs0: SUCCESS (2.201s)
[146.924782] Starting dynamic subtest: vcs0
[149.051201] Dynamic subtest vcs0: SUCCESS (2.136s)
[149.069034] Starting dynamic subtest: vcs1
[151.002945] Dynamic subtest vcs1: SUCCESS (1.950s)
[151.022585] Starting dynamic subtest: vecs0
[153.050541] Dynamic subtest vecs0: SUCCESS (2.046s)
[153.069286] Starting dynamic subtest: ccs0
[155.334751] Dynamic subtest ccs0: SUCCESS (2.285s)
[155.337868] Subtest basic: SUCCESS (12.880s)
[155.522139] [026/144] (894s left) i915_pm_rpm (reg-read-ioctl)
[157.154150] Starting subtest: reg-read-ioctl
[164.733748] Subtest reg-read-ioctl: SUCCESS (7.579s)
[165.576071] [027/144] (884s left) syncobj_timeline (wait-all-complex)
[165.646303] Starting subtest: wait-all-complex
[165.866866] Subtest wait-all-complex: SUCCESS (0.220s)
[165.942252] [028/144] (884s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-plflip-blt)
[166.046451] Starting subtest: psr-1p-primscrn-shrfb-plflip-blt
[171.402611] Subtest psr-1p-primscrn-shrfb-plflip-blt: SUCCESS (5.361s)
[171.523737] [029/144] (878s left) gem_exec_schedule (preempt-queue)
[171.623358] Subtest preempt-queue: SKIP (0.000s)
[171.924509] [030/144] (878s left) perf (create-destroy-userspace-config)
[172.058365] Starting subtest: create-destroy-userspace-config
[172.084928] Subtest create-destroy-userspace-config: SUCCESS (0.028s)
[172.119420] [031/144] (878s left) kms_psr2_su (page_flip-xrgb8888)
[172.178434] Subtest page_flip-XRGB8888: SKIP (0.000s)
[172.216624] [032/144] (878s left) kms_ccs (pipe-b-bad-pixel-format-y-tiled-gen12-rc-ccs)
[172.637621] Starting subtest: pipe-B-bad-pixel-format-y-tiled-gen12-rc-ccs
[172.643151] Subtest pipe-B-bad-pixel-format-y-tiled-gen12-rc-ccs: SKIP (0.000s)
[173.521656] [033/144] (876s left) v3d/v3d_submit_csd (single-in-sync)
[173.586411] Subtest single-in-sync: SKIP (0.000s)
[173.635499] [034/144] (876s left) kms_cursor_legacy (forked-move)
[173.750252] Starting subtest: forked-move
[173.752772] Starting dynamic subtest: pipe-A
[179.223046] Dynamic subtest pipe-A: SUCCESS (5.469s)
[179.241510] Starting dynamic subtest: pipe-B
[184.789279] Dynamic subtest pipe-B: SUCCESS (5.565s)
[184.807498] Starting dynamic subtest: pipe-C
[190.373381] Dynamic subtest pipe-C: SUCCESS (5.583s)
[190.399305] Starting dynamic subtest: pipe-D
[195.934411] Dynamic subtest pipe-D: SUCCESS (5.559s)
[195.954564] Starting dynamic subtest: all-pipes
[201.499447] Dynamic subtest all-pipes: SUCCESS (5.566s)
[201.502794] Subtest forked-move: SUCCESS (27.747s)
[201.663863] [035/144] (848s left) gem_render_copy (y-tiled-mc-ccs-to-yf-tiled-ccs)
[201.738047] Starting subtest: y-tiled-mc-ccs-to-yf-tiled-ccs
[201.743626] Subtest y-tiled-mc-ccs-to-yf-tiled-ccs: SKIP (0.000s)
[201.923328] [036/144] (848s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-onoff)
[202.017689] Starting subtest: psr-2p-scndscrn-spr-indfb-onoff
[202.021747] Subtest psr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[202.109722] [037/144] (848s left) perf_pmu (rc6-all-gts)
[202.152838] Starting subtest: rc6-all-gts
[208.510397] Subtest rc6-all-gts: SUCCESS (6.358s)
[209.263521] [038/144] (841s left) syncobj_wait (wait-all-for-submit-complex)
[209.334085] Starting subtest: wait-all-for-submit-complex
[209.550025] Subtest wait-all-for-submit-complex: SUCCESS (0.217s)
[209.632153] [039/144] (840s left) vc4/vc4_perfmon (destroy-invalid-perfmon)
[209.724833] Subtest destroy-invalid-perfmon: SKIP (0.000s)
[209.776651] [040/144] (840s left) kms_cursor_crc (cursor-rapid-movement-32x10)
[209.924335] Starting subtest: cursor-rapid-movement-32x10
[209.979549] Subtest cursor-rapid-movement-32x10: SKIP (0.048s)
[210.253920] [041/144] (840s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-move)
[210.359061] Starting subtest: fbc-1p-primscrn-cur-indfb-move
[213.375858] Subtest fbc-1p-primscrn-cur-indfb-move: SUCCESS (3.022s)
[213.517049] [042/144] (837s left) syncobj_wait (invalid-wait-illegal-handle)
[213.592129] Starting subtest: invalid-wait-illegal-handle
[213.594629] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[213.681381] [043/144] (836s left) kms_ccs (pipe-b-random-ccs-data-y-tiled-gen12-rc-ccs)
[214.076456] Starting subtest: pipe-B-random-ccs-data-y-tiled-gen12-rc-ccs
[214.085223] Subtest pipe-B-random-ccs-data-y-tiled-gen12-rc-ccs: SKIP (0.000s)
[214.940673] [044/144] (835s left) kms_big_fb (yf-tiled-32bpp-rotate-90)
[215.047005] Starting subtest: yf-tiled-32bpp-rotate-90
[215.051445] Subtest yf-tiled-32bpp-rotate-90: SKIP (0.000s)
[215.140552] [045/144] (835s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-blt)
[215.219898] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-blt
[215.223961] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[215.298371] [046/144] (835s left) gen9_exec_parse (shadow-peek)
[215.373482] Subtest shadow-peek: SKIP (0.000s)
[215.441792] [047/144] (835s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-move)
[215.540174] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-move
[215.545286] Subtest fbcpsr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[215.627168] [048/144] (835s left) core_getstats
[215.786319] [049/144] (835s left) kms_plane_scaling (planes-upscale-20x20-downscale-factor-0-75)
[215.877354] Starting subtest: planes-upscale-20x20-downscale-factor-0-75
[215.879472] Starting dynamic subtest: pipe-A-eDP-1
[215.889277] Dynamic subtest pipe-A-eDP-1: SKIP (0.010s)
[215.899048] Starting dynamic subtest: pipe-B-eDP-1
[215.907616] Dynamic subtest pipe-B-eDP-1: SKIP (0.018s)
[215.925062] Starting dynamic subtest: pipe-C-eDP-1
[215.929496] Dynamic subtest pipe-C-eDP-1: SKIP (0.018s)
[215.942143] Starting dynamic subtest: pipe-D-eDP-1
[215.945859] Dynamic subtest pipe-D-eDP-1: SKIP (0.018s)
[215.949160] Subtest planes-upscale-20x20-downscale-factor-0-75: SKIP (0.067s)
[216.012794] [050/144] (834s left) gem_exec_balancer (bonded-semaphore)
[216.055642] Starting subtest: bonded-semaphore
[216.059603] Subtest bonded-semaphore: SKIP (0.000s)
[216.318214] [051/144] (834s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted-signaled)
[216.378544] Starting subtest: etime-multi-wait-all-for-submit-submitted-signaled
[216.486669] Subtest etime-multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.106s)
[216.565447] [052/144] (834s left) gem_render_copy (mixed-tiled-to-y-tiled-ccs)
[216.623620] Starting subtest: mixed-tiled-to-y-tiled-ccs
[216.629957] Subtest mixed-tiled-to-y-tiled-ccs: SKIP (0.000s)
[216.805076] [053/144] (834s left) kms_big_fb (x-tiled-8bpp-rotate-90)
[216.915896] Starting subtest: x-tiled-8bpp-rotate-90
[216.937518] Subtest x-tiled-8bpp-rotate-90: SKIP (0.019s)
[217.025744] [054/144] (833s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-render)
[217.124409] Starting subtest: psr-1p-primscrn-pri-indfb-draw-render
[222.256294] Subtest psr-1p-primscrn-pri-indfb-draw-render: SUCCESS (5.139s)
[222.344539] [055/144] (828s left) kms_busy (extended-modeset-hang-newfb-with-reset)
[222.434018] Starting subtest: extended-modeset-hang-newfb-with-reset
[222.436757] Starting dynamic subtest: pipe-A
[236.730960] Dynamic subtest pipe-A: SUCCESS (14.293s)
[236.743881] Starting dynamic subtest: pipe-D
[252.252223] Dynamic subtest pipe-D: SUCCESS (15.516s)
[252.255663] Subtest extended-modeset-hang-newfb-with-reset: SUCCESS (29.818s)
[253.150320] [056/144] (797s left) kms_flip (2x-flip-vs-rmfb)
[253.260774] Starting subtest: 2x-flip-vs-rmfb
[253.272929] Subtest 2x-flip-vs-rmfb: SKIP (0.013s)
[253.350892] [057/144] (797s left) gem_exec_params (rsvd2-dirt)
[253.428166] Starting subtest: rsvd2-dirt
[253.433358] Subtest rsvd2-dirt: SKIP (0.000s)
[253.525534] [058/144] (797s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-upscaling)
[253.624181] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-upscaling
[253.626673] Starting dynamic subtest: pipe-A-default-mode
[253.917118] Dynamic subtest pipe-A-default-mode: SKIP (0.288s)
[253.922113] Subtest flip-32bpp-yftile-to-64bpp-yftile-upscaling: SKIP (0.290s)
[254.814577] [059/144] (796s left) kms_psr (fbc-psr2-sprite-mmap-gtt)
[254.905016] Starting subtest: fbc-psr2-sprite-mmap-gtt
[254.907923] Starting dynamic subtest: eDP-1
[254.913650] Dynamic subtest eDP-1: SKIP (0.000s)
[254.920647] Subtest fbc-psr2-sprite-mmap-gtt: SKIP (0.001s)
[255.037562] [060/144] (796s left) prime_mmap (test_aperture_limit)
[255.104361] Starting subtest: test_aperture_limit
[255.106683] Starting dynamic subtest: test_aperture_limit-smem
[255.119481] Dynamic subtest test_aperture_limit-smem: SUCCESS (0.017s)
[255.122028] Subtest test_aperture_limit: SUCCESS (0.018s)
[255.212272] [061/144] (795s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions-varying-size)
[255.325004] Starting subtest: short-flip-after-cursor-atomic-transitions-varying-size
[256.348952] Subtest short-flip-after-cursor-atomic-transitions-varying-size: SUCCESS (1.024s)
[257.243861] [062/144] (794s left) v3d/v3d_perfmon (create-perfmon-exceed)
[257.323704] Subtest create-perfmon-exceed: SKIP (0.000s)
[257.377374] [063/144] (793s left) kms_flip (2x-plain-flip-interruptible)
[257.479456] Starting subtest: 2x-plain-flip-interruptible
[257.494072] Subtest 2x-plain-flip-interruptible: SKIP (0.013s)
[257.568388] [064/144] (793s left) gem_exec_flush (basic-uc-set-default)
[257.657742] Starting subtest: basic-uc-set-default
[263.132055] Subtest basic-uc-set-default: SUCCESS (5.476s)
[263.325258] [065/144] (788s left) api_intel_bb (object-noreloc-purge-cache-simple)
[263.393058] Starting subtest: object-noreloc-purge-cache-simple
[263.403858] Subtest object-noreloc-purge-cache-simple: SUCCESS (0.014s)
[263.494126] [066/144] (787s left) gem_busy (semaphore)
[263.719019] Subtest semaphore: SKIP (0.000s)
[263.930964] [067/144] (787s left) kms_big_fb (y-tiled-32bpp-rotate-180)
[264.045259] Starting subtest: y-tiled-32bpp-rotate-180
[264.049242] Subtest y-tiled-32bpp-rotate-180: SKIP (0.000s)
[264.134136] [068/144] (787s left) kms_plane_scaling (intel-max-src-size)
[264.227065] Starting subtest: intel-max-src-size
[264.231927] Subtest intel-max-src-size: SKIP (0.001s)
[264.328533] [069/144] (787s left) syncobj_wait (invalid-single-wait-all-unsubmitted)
[264.399898] Starting subtest: invalid-single-wait-all-unsubmitted
[264.402259] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[264.487025] [070/144] (787s left) kms_flip (2x-flip-vs-dpms)
[264.559089] Starting subtest: 2x-flip-vs-dpms
[264.570626] Subtest 2x-flip-vs-dpms: SKIP (0.013s)
[264.642646] [071/144] (786s left) kms_psr (psr2-dpms)
[264.731749] Starting subtest: psr2-dpms
[264.734394] Starting dynamic subtest: eDP-1
[267.868037] Dynamic subtest eDP-1: SUCCESS (3.139s)
[267.871214] Subtest psr2-dpms: SUCCESS (3.140s)
[268.765617] [072/144] (782s left) gem_exec_fence (expired-history)
[269.239929] Starting subtest: expired-history
[276.736149] Subtest expired-history: SUCCESS (7.498s)
[277.337419] [073/144] (774s left) gem_media_vme
[277.465353] [074/144] (774s left) dmabuf (all-tests)
[277.520626] Starting subtest: all-tests
[277.522918] Starting dynamic subtest: sanitycheck
[277.581679] Dynamic subtest sanitycheck: SUCCESS (0.065s)
[277.603033] Starting dynamic subtest: dma_fence
[277.764617] Dynamic subtest dma_fence: SUCCESS (0.181s)
[277.785384] Starting dynamic subtest: dma_fence_chain
[287.021408] Dynamic subtest dma_fence_chain: SUCCESS (9.254s)
[287.035114] Starting dynamic subtest: dma_fence_unwrap
[287.087178] Dynamic subtest dma_fence_unwrap: SUCCESS (0.066s)
[287.108557] Starting dynamic subtest: dma_resv
[287.166158] Dynamic subtest dma_resv: SUCCESS (0.078s)
[287.168967] Subtest all-tests: SUCCESS (9.649s)
[287.233840] [075/144] (764s left) gem_exec_params (invalid-bsd2-flag-on-render)
[287.299586] Starting subtest: invalid-bsd2-flag-on-render
[287.302180] Subtest invalid-bsd2-flag-on-render: SUCCESS (0.000s)
[287.388495] [076/144] (764s left) gem_exec_capture (many-4k-zero)
[287.469334] Starting subtest: many-4K-zero
[287.978934] Subtest many-4K-zero: FAIL (0.448s)
[288.052668] [077/144] (763s left) kms_psr (psr-cursor-mmap-cpu)
[288.204976] Starting subtest: psr-cursor-mmap-cpu
[288.207377] Starting dynamic subtest: eDP-1
[290.048939] Dynamic subtest eDP-1: SUCCESS (1.842s)
[290.051840] Subtest psr-cursor-mmap-cpu: SUCCESS (1.845s)
[290.975774] [078/144] (760s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[291.089584] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[291.100621] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.013s)
[291.174029] [079/144] (760s left) sysfs_heartbeat_interval (invalid)
[291.233446] Starting subtest: invalid
[291.236076] Starting dynamic subtest: vecs0
[291.238639] Dynamic subtest vecs0: SUCCESS (0.000s)
[291.249592] Starting dynamic subtest: ccs0
[291.251920] Dynamic subtest ccs0: SUCCESS (0.000s)
[291.261062] Starting dynamic subtest: vcs0
[291.263532] Dynamic subtest vcs0: SUCCESS (0.000s)
[291.271688] Starting dynamic subtest: bcs0
[291.274078] Dynamic subtest bcs0: SUCCESS (0.000s)
[291.281793] Starting dynamic subtest: rcs0
[291.284168] Dynamic subtest rcs0: SUCCESS (0.000s)
[291.291650] Starting dynamic subtest: vcs1
[291.293685] Dynamic subtest vcs1: SUCCESS (0.000s)
[291.295855] Subtest invalid: SUCCESS (0.010s)
[291.321094] [080/144] (760s left) kms_ccs (pipe-a-crc-sprite-planes-basic-4-tiled-mtl-rc-ccs)
[291.722065] Starting subtest: pipe-A-crc-sprite-planes-basic-4-tiled-mtl-rc-ccs
[293.742710] Subtest pipe-A-crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SUCCESS (2.021s)
[294.933792] [081/144] (756s left) gem_softpin (noreloc-s3)
[295.017043] Starting subtest: noreloc-S3
[315.343727] Subtest noreloc-S3: SUCCESS (5.419s)
[315.454709] [082/144] (751s left) kms_flip (2x-flip-vs-fences-interruptible)
[315.559676] Starting subtest: 2x-flip-vs-fences-interruptible
[315.564363] Subtest 2x-flip-vs-fences-interruptible: SKIP (0.000s)
[315.644100] [083/144] (751s left) gem_exec_fence (syncobj-wait)
[316.115978] Starting subtest: syncobj-wait
[318.000264] Subtest syncobj-wait: SUCCESS (1.883s)
[320.001866] [084/144] (746s left) kms_ccs (pipe-c-bad-rotation-90-y-tiled-ccs)
[320.455199] Starting subtest: pipe-C-bad-rotation-90-y-tiled-ccs
[320.460612] Subtest pipe-C-bad-rotation-90-y-tiled-ccs: SKIP (0.000s)
[321.306145] [085/144] (745s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-render)
[321.378439] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-render
[324.599011] Subtest fbc-1p-primscrn-pri-indfb-draw-render: SUCCESS (3.225s)
[324.728580] [086/144] (742s left) kms_universal_plane (universal-plane-functional)
[324.808255] Starting subtest: universal-plane-functional
[324.810618] Starting dynamic subtest: pipe-A-eDP-1
[329.091665] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.280s)
[329.109702] Starting dynamic subtest: pipe-B-eDP-1
[334.110869] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.013s)
[334.131304] Starting dynamic subtest: pipe-C-eDP-1
[338.804820] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.693s)
[338.822358] Starting dynamic subtest: pipe-D-eDP-1
[343.489684] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.682s)
[343.492512] Subtest universal-plane-functional: SUCCESS (18.682s)
[344.406330] [087/144] (722s left) gem_userptr_blits (create-destroy-unsync)
[344.497778] Subtest create-destroy-unsync: SKIP (0.000s)
[344.619882] [088/144] (722s left) gem_exec_store (pages)
[344.703955] Starting subtest: pages
[344.706718] Starting dynamic subtest: rcs0
[344.788721] Dynamic subtest rcs0: SUCCESS (0.084s)
[344.811831] Starting dynamic subtest: bcs0
[344.868090] Dynamic subtest bcs0: SUCCESS (0.079s)
[344.887768] Starting dynamic subtest: vcs0
[344.944576] Dynamic subtest vcs0: SUCCESS (0.075s)
[344.964311] Starting dynamic subtest: vcs1
[345.020638] Dynamic subtest vcs1: SUCCESS (0.074s)
[345.041090] Starting dynamic subtest: vecs0
[345.104516] Dynamic subtest vecs0: SUCCESS (0.083s)
[345.124597] Starting dynamic subtest: ccs0
[345.202699] Dynamic subtest ccs0: SUCCESS (0.097s)
[345.205323] Subtest pages: SUCCESS (0.500s)
[345.394995] [089/144] (721s left) gem_eio (in-flight-internal-1us)
[345.645047] Starting subtest: in-flight-internal-1us
[347.072368] Subtest in-flight-internal-1us: SUCCESS (1.426s)
[347.187870] [090/144] (719s left) gem_ctx_persistence (engines-mixed-process)
[347.252116] Starting subtest: engines-mixed-process
[347.254702] Starting dynamic subtest: rcs0
[348.864782] Dynamic subtest rcs0: SUCCESS (1.611s)
[348.885783] Starting dynamic subtest: bcs0
[350.923914] Dynamic subtest bcs0: SUCCESS (2.058s)
[350.945229] Starting dynamic subtest: vcs0
[352.919888] Dynamic subtest vcs0: SUCCESS (1.990s)
[352.941653] Starting dynamic subtest: vcs1
[354.901298] Dynamic subtest vcs1: SUCCESS (1.984s)
[354.923238] Starting dynamic subtest: vecs0
[356.941880] Dynamic subtest vecs0: SUCCESS (2.039s)
[356.963224] Starting dynamic subtest: ccs0
[358.931861] Dynamic subtest ccs0: SUCCESS (1.988s)
[358.934583] Subtest engines-mixed-process: SUCCESS (11.680s)
[359.021547] [091/144] (708s left) kms_psr (pr-cursor-mmap-gtt)
[359.114882] Starting subtest: pr-cursor-mmap-gtt
[359.119360] Subtest pr-cursor-mmap-gtt: SKIP (0.000s)
[359.215154] [092/144] (707s left) syncobj_wait (wait-any-interrupted)
[359.317230] Starting subtest: wait-any-interrupted
[359.421404] Subtest wait-any-interrupted: SUCCESS (0.102s)
[359.490771] [093/144] (707s left) kms_ccs (pipe-d-bad-pixel-format-4-tiled-dg2-mc-ccs)
[359.900339] Starting subtest: pipe-D-bad-pixel-format-4-tiled-dg2-mc-ccs
[359.906071] Subtest pipe-D-bad-pixel-format-4-tiled-dg2-mc-ccs: SKIP (0.000s)
[360.790078] [094/144] (706s left) gem_exec_balancer (parallel-bb-first)
[361.018759] Starting subtest: parallel-bb-first
[361.660873] Subtest parallel-bb-first: SUCCESS (0.642s)
[363.009886] [095/144] (704s left) kms_ccs (pipe-d-bad-rotation-90-4-tiled-mtl-mc-ccs)
[363.449214] Starting subtest: pipe-D-bad-rotation-90-4-tiled-mtl-mc-ccs
[364.537631] Subtest pipe-D-bad-rotation-90-4-tiled-mtl-mc-ccs: SUCCESS (1.088s)
[365.722841] [096/144] (701s left) kms_cursor_legacy (2x-flip-vs-cursor-legacy)
[365.855980] Starting subtest: 2x-flip-vs-cursor-legacy
[365.862661] Subtest 2x-flip-vs-cursor-legacy: SKIP (0.005s)
[366.014833] [097/144] (701s left) kms_ccs (pipe-d-missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc)
[366.458619] Starting subtest: pipe-D-missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc
[366.464101] Subtest pipe-D-missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc: SKIP (0.000s)
[367.321091] [098/144] (699s left) gem_spin_batch (resubmit-all)
[367.386881] Starting subtest: resubmit-all
[367.389622] Starting dynamic subtest: rcs0
[367.413059] Dynamic subtest rcs0: SUCCESS (0.026s)
[367.434015] Starting dynamic subtest: bcs0
[367.436388] Dynamic subtest bcs0: SUCCESS (0.002s)
[367.453744] Starting dynamic subtest: vcs0
[367.455829] Dynamic subtest vcs0: SUCCESS (0.002s)
[367.472622] Starting dynamic subtest: vcs1
[367.474806] Dynamic subtest vcs1: SUCCESS (0.002s)
[367.485072] Starting dynamic subtest: vecs0
[367.487171] Dynamic subtest vecs0: SUCCESS (0.001s)
[367.495225] Starting dynamic subtest: ccs0
[367.497305] Dynamic subtest ccs0: SUCCESS (0.002s)
[367.499392] Subtest resubmit-all: SUCCESS (0.038s)
[368.959337] [099/144] (698s left) kms_pm_rpm (dpms-lpsp)
[371.113450] Starting subtest: dpms-lpsp
[371.449173] Subtest dpms-lpsp: SUCCESS (0.336s)
[371.573606] [100/144] (695s left) kms_content_protection (atomic)
[371.697405] Starting subtest: atomic
[375.270964] Subtest atomic: SKIP (3.571s)
[376.549270] [101/144] (690s left) gem_set_tiling_vs_gtt
[376.684253] [102/144] (690s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-plflip-blt)
[376.792557] Starting subtest: fbc-2p-scndscrn-shrfb-plflip-blt
[376.796587] Subtest fbc-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[376.879422] [103/144] (690s left) gem_userptr_blits (probe)
[376.972696] Starting subtest: probe
[377.124320] Subtest probe: SUCCESS (0.150s)
[377.226991] [104/144] (690s left) vc4/vc4_wait_bo (unused-bo-1ns)
[377.333092] Subtest unused-bo-1ns: SKIP (0.000s)
[377.390023] [105/144] (690s left) i915_getparams_basic (basic-eu-total)
[377.451322] Starting subtest: basic-eu-total
[377.457026] Subtest basic-eu-total: SUCCESS (0.000s)
[377.539108] [106/144] (690s left) gem_exec_schedule (pi-distinct-iova)
[377.739070] Starting subtest: pi-distinct-iova
[377.741600] Starting dynamic subtest: rcs0
[377.839770] Dynamic subtest rcs0: SUCCESS (0.103s)
[377.861473] Starting dynamic subtest: vcs0
[377.914007] Dynamic subtest vcs0: SUCCESS (0.073s)
[377.929085] Starting dynamic subtest: vcs1
[377.983188] Dynamic subtest vcs1: SUCCESS (0.069s)
[377.990728] Starting dynamic subtest: vecs0
[378.078416] Dynamic subtest vecs0: SUCCESS (0.094s)
[378.099547] Starting dynamic subtest: ccs0
[378.152334] Dynamic subtest ccs0: SUCCESS (0.073s)
[378.155474] Subtest pi-distinct-iova: SUCCESS (0.417s)
[379.070183] [107/144] (688s left) kms_getfb (getfb2-handle-not-fb)
[379.159500] Starting subtest: getfb2-handle-not-fb
[379.179703] Subtest getfb2-handle-not-fb: SUCCESS (0.018s)
[379.269684] [108/144] (688s left) kms_cursor_crc (cursor-onscreen-256x256)
[379.554671] Starting subtest: cursor-onscreen-256x256
[379.567368] Starting dynamic subtest: pipe-A-eDP-1
[382.048633] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.482s)
[382.091323] Starting dynamic subtest: pipe-D-eDP-1
[385.194858] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.124s)
[385.197713] Subtest cursor-onscreen-256x256: SUCCESS (5.640s)
[386.088967] [109/144] (681s left) kms_feature_discovery (display)
[386.189214] Starting subtest: display
[386.192382] Subtest display: SUCCESS (0.000s)
[386.259007] [110/144] (681s left) kms_writeback (writeback-fb-id-xrgb2101010)
[386.360933] Subtest writeback-fb-id-XRGB2101010: SKIP (0.000s)
[386.385191] [111/144] (681s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-0)
[386.508646] Starting subtest: linear-max-hw-stride-32bpp-rotate-0
[387.605280] Subtest linear-max-hw-stride-32bpp-rotate-0: SUCCESS (1.100s)
[387.720908] [112/144] (680s left) syncobj_eventfd (timeline-wait)
[387.794246] Starting subtest: timeline-wait
[387.802593] Subtest timeline-wait: SUCCESS (0.011s)
[387.880695] [113/144] (679s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[387.955176] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-hflip
[389.122742] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-hflip: SUCCESS (1.168s)
[389.229071] [114/144] (678s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-pwrite)
[389.325967] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-pwrite
[389.329431] Subtest psr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[389.418758] [115/144] (678s left) kms_cursor_legacy (cursor-vs-flip-varying-size)
[389.542767] Starting subtest: cursor-vs-flip-varying-size
[419.036818] Subtest cursor-vs-flip-varying-size: SUCCESS (29.491s)
[419.930282] [116/144] (647s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-downscaling)
[420.025362] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-downscaling
[420.027868] Starting dynamic subtest: pipe-A-default-mode
[420.314948] Dynamic subtest pipe-A-default-mode: SKIP (0.288s)
[420.320365] Subtest flip-64bpp-ytile-to-16bpp-ytile-downscaling: SKIP (0.290s)
[421.194685] [117/144] (646s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-blt)
[421.283569] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-blt
[426.150106] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-blt: SUCCESS (4.869s)
[426.274282] [118/144] (641s left) kms_ccs (pipe-d-crc-primary-basic-y-tiled-gen12-rc-ccs-cc)
[426.691089] Starting subtest: pipe-D-crc-primary-basic-y-tiled-gen12-rc-ccs-cc
[426.696769] Subtest pipe-D-crc-primary-basic-y-tiled-gen12-rc-ccs-cc: SKIP (0.000s)
[427.543690] [119/144] (640s left) gem_mmap_gtt (big-bo)
[427.609445] Subtest big-bo: SKIP (0.000s)
[427.661966] [120/144] (640s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted)
[427.727919] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted
[427.730363] Subtest multi-wait-for-submit-available-unsubmitted-submitted: SUCCESS (0.001s)
[427.816236] [121/144] (640s left) kms_frontbuffer_tracking (fbc-rgb565-draw-render)
[427.930745] Starting subtest: fbc-rgb565-draw-render
[431.010787] Subtest fbc-rgb565-draw-render: SUCCESS (3.086s)
[431.148564] [122/144] (636s left) kms_chamelium_frames (hdmi-aspect-ratio)
[431.495434] Subtest hdmi-aspect-ratio: SKIP (0.000s)
[432.383162] [123/144] (635s left) kms_ccs (pipe-c-crc-sprite-planes-basic-y-tiled-gen12-mc-ccs)
[432.778685] Starting subtest: pipe-C-crc-sprite-planes-basic-y-tiled-gen12-mc-ccs
[432.783661] Subtest pipe-C-crc-sprite-planes-basic-y-tiled-gen12-mc-ccs: SKIP (0.001s)
[433.623380] [124/144] (634s left) gem_ctx_persistence (smoketest)
[433.693938] Starting subtest: smoketest
[464.891305] Subtest smoketest: SUCCESS (31.195s)
[464.954663] [125/144] (603s left) kms_dsc (dsc-fractional-bpp-with-bpc)
[465.053964] Starting subtest: dsc-fractional-bpp-with-bpc
[465.058596] Subtest dsc-fractional-bpp-with-bpc: SKIP (0.002s)
[465.129567] [126/144] (603s left) kms_color (ctm-0-25)
[465.247276] Starting subtest: ctm-0-25
[465.249842] Starting dynamic subtest: pipe-A
[468.944806] Dynamic subtest pipe-A: SUCCESS (3.692s)
[468.965843] Starting dynamic subtest: pipe-B
[473.426008] Dynamic subtest pipe-B: SUCCESS (4.481s)
[473.447029] Starting dynamic subtest: pipe-C
[477.818204] Dynamic subtest pipe-C: SUCCESS (4.392s)
[477.832334] Starting dynamic subtest: pipe-D
[482.215520] Dynamic subtest pipe-D: SUCCESS (4.398s)
[482.218006] Subtest ctm-0-25: SUCCESS (16.969s)
[483.156328] [127/144] (585s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[483.224658] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[483.328001] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.103s)
[483.408348] [128/144] (584s left) perf_pmu (busy-idle)
[483.480141] Starting subtest: busy-idle
[483.482702] Starting dynamic subtest: rcs0
[484.166418] Dynamic subtest rcs0: SUCCESS (0.685s)
[484.188328] Starting dynamic subtest: bcs0
[484.838623] Dynamic subtest bcs0: SUCCESS (0.670s)
[484.860615] Starting dynamic subtest: vcs0
[485.971469] Dynamic subtest vcs0: SUCCESS (1.130s)
[485.994023] Starting dynamic subtest: vcs1
[487.956215] Dynamic subtest vcs1: SUCCESS (1.982s)
[487.978558] Starting dynamic subtest: vecs0
[489.940532] Dynamic subtest vecs0: SUCCESS (1.982s)
[489.962795] Starting dynamic subtest: ccs0
[490.616451] Dynamic subtest ccs0: SUCCESS (0.676s)
[490.619836] Subtest busy-idle: SUCCESS (7.137s)
[490.714523] [129/144] (577s left) kms_big_fb (4-tiled-32bpp-rotate-90)
[490.818398] Starting subtest: 4-tiled-32bpp-rotate-90
[490.836205] Subtest 4-tiled-32bpp-rotate-90: SKIP (0.018s)
[490.943026] [130/144] (577s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[491.014649] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[491.019150] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[491.102871] [131/144] (577s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[491.192473] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[491.196254] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[491.262844] [132/144] (577s left) gem_huc_copy (huc-copy)
[491.334840] Starting subtest: huc-copy
[491.337886] Subtest huc-copy: SUCCESS (0.003s)
[491.442443] [133/144] (576s left) i915_pm_rps (thresholds-idle)
[491.509307] Starting subtest: thresholds-idle
[491.512291] Starting dynamic subtest: gt0
[491.517444] Dynamic subtest gt0: SKIP (0.005s)
[491.531474] Starting dynamic subtest: gt1
[491.536693] Dynamic subtest gt1: SKIP (0.000s)
[491.541869] Subtest thresholds-idle: SKIP (0.006s)
[491.589325] [134/144] (576s left) gem_softpin (overlap)
[491.686754] Starting subtest: overlap
[492.512910] Subtest overlap: SUCCESS (0.824s)
[492.613301] [135/144] (575s left) gem_create (hog-create)
[492.700941] Starting subtest: hog-create
[492.703485] Starting dynamic subtest: smem0
[508.149933] Dynamic subtest smem0: FAIL (15.365s)
[508.152025] Subtest hog-create: FAIL (15.365s)
[510.044162] [136/144] (558s left) gem_ctx_param (basic-default)
[510.096298] Starting subtest: basic-default
[510.098871] Subtest basic-default: SUCCESS (0.000s)
[510.169002] [137/144] (558s left) kms_ccs (pipe-b-crc-primary-basic-y-tiled-gen12-mc-ccs)
[510.580329] Starting subtest: pipe-B-crc-primary-basic-y-tiled-gen12-mc-ccs
[510.585616] Subtest pipe-B-crc-primary-basic-y-tiled-gen12-mc-ccs: SKIP (0.000s)
[511.446138] [138/144] (557s left) gem_mmap_offset (oob-read)
[511.520940] Starting subtest: oob-read
[511.523016] Subtest oob-read: SUCCESS (0.000s)
[511.606671] [139/144] (557s left) vgem_slow (nohang)
[511.650465] Starting subtest: nohang
[521.967732] Subtest nohang: SUCCESS (10.323s)
[522.042818] [140/144] (546s left) kms_psr2_sf (overlay-plane-move-continuous-sf)
[527.371699] Starting subtest: overlay-plane-move-continuous-sf
[527.378284] Starting dynamic subtest: pipe-A-eDP-1
[534.143644] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.767s)
[534.165471] Starting dynamic subtest: pipe-B-eDP-1
[540.942025] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.796s)
[540.944635] Subtest overlay-plane-move-continuous-sf: SUCCESS (13.571s)
[541.847455] [141/144] (526s left) drm_fdinfo (virtual-busy-all)
[541.897113] Starting subtest: virtual-busy-all
[541.902077] Subtest virtual-busy-all: SKIP (0.000s)
[541.963883] [142/144] (526s left) drm_read (empty-block)
[542.124684] Starting subtest: empty-block
[543.124507] Subtest empty-block: SUCCESS (1.000s)
[543.264549] [143/144] (525s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-msflip-blt)
[543.370213] Starting subtest: fbcpsr-1p-primscrn-indfb-msflip-blt
[548.536197] Subtest fbcpsr-1p-primscrn-indfb-msflip-blt: SUCCESS (5.170s)
[548.672800] [144/144] (520s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[548.771776] Starting subtest: addfb25-y-tiled-small-legacy
[548.776527] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[548.854439] Closing watchdogs
Done.