[54.704935] Initializing watchdogs
[54.705353]   /dev/watchdog0
[54.721192] [001/142] (960s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[55.047453] Starting subtest: basic-busy-flip-before-cursor-legacy
[55.050213] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.001s)
[55.138308] [002/142] (960s left) gem_exec_reloc (basic-cpu-gtt-noreloc)
[55.379086] Starting subtest: basic-cpu-gtt-noreloc
[55.405929] Subtest basic-cpu-gtt-noreloc: SUCCESS (0.027s)
[55.587273] [003/142] (959s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-cpu)
[55.907499] Starting subtest: fbcpsr-rgb565-draw-mmap-cpu
[55.910456] Subtest fbcpsr-rgb565-draw-mmap-cpu: SKIP (0.000s)
[56.003677] [004/142] (959s left) kms_ccs (pipe-d-random-ccs-data-4_tiled_dg2_rc_ccs_cc)
[56.734132] Subtest pipe-D-random-ccs-data-4_tiled_dg2_rc_ccs_cc: SKIP (0.000s)
[57.062497] [005/142] (958s left) kms_big_fb (yf-tiled-64bpp-rotate-180)
[57.390553] Starting subtest: yf-tiled-64bpp-rotate-180
[57.393425] Subtest yf-tiled-64bpp-rotate-180: SKIP (0.000s)
[57.495390] [006/142] (957s left) kms_flip (flip-vs-wf_vblank-interruptible)
[57.801999] Starting subtest: flip-vs-wf_vblank-interruptible
[57.805574] Starting dynamic subtest: A-DP1
[59.511249] Dynamic subtest A-DP1: SUCCESS (1.705s)
[59.525399] Starting dynamic subtest: B-DP1
[60.931404] Dynamic subtest B-DP1: SUCCESS (1.419s)
[60.947220] Starting dynamic subtest: C-DP1
[62.334429] Dynamic subtest C-DP1: SUCCESS (1.399s)
[62.336248] Subtest flip-vs-wf_vblank-interruptible: SUCCESS (4.534s)
[62.725587] [007/142] (952s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-blt)
[63.035830] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-blt
[63.038445] Subtest fbc-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[63.127746] [008/142] (952s left) gem_mmap (basic)
[63.335801] Starting subtest: basic
[63.342802] Subtest basic: SUCCESS (0.002s)
[63.423832] [009/142] (952s left) gem_workarounds (reset)
[63.662481] Starting subtest: reset
[63.693343] Subtest reset: SUCCESS (0.031s)
[63.792460] [010/142] (951s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-cpu)
[64.110367] Starting subtest: psr-rgb565-draw-mmap-cpu
[64.112912] Subtest psr-rgb565-draw-mmap-cpu: SKIP (0.000s)
[64.208128] [011/142] (951s left) sysfs_heartbeat_interval (nopreempt)
[64.421257] Starting subtest: nopreempt
[64.422978] Starting dynamic subtest: vecs0
[67.930690] Dynamic subtest vecs0: SUCCESS (3.508s)
[67.945279] Starting dynamic subtest: vcs0
[71.441612] Dynamic subtest vcs0: SUCCESS (3.509s)
[71.461097] Starting dynamic subtest: bcs0
[74.917491] Dynamic subtest bcs0: SUCCESS (3.477s)
[74.935542] Starting dynamic subtest: rcs0
[78.408530] Dynamic subtest rcs0: SUCCESS (3.489s)
[78.410025] Subtest nopreempt: SUCCESS (13.986s)
[78.491092] [012/142] (937s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-msflip-blt)
[78.805669] Starting subtest: psr-2p-scndscrn-shrfb-msflip-blt
[78.808329] Subtest psr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[78.890447] [013/142] (936s left) kms_cursor_crc (cursor-offscreen-max-size)
[79.211793] Starting subtest: cursor-offscreen-max-size
[79.216377] Subtest cursor-offscreen-max-size: SKIP (0.000s)
[79.307882] [014/142] (936s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[79.627970] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip
[79.657283] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.028s)
[79.757423] [015/142] (935s left) kms_chamelium_hpd (vga-hpd-enable-disable-mode)
[80.138880] Subtest vga-hpd-enable-disable-mode: SKIP (0.000s)
[80.422103] [016/142] (935s left) i915_pm_rps (engine-order)
[80.639609] Starting subtest: engine-order
[95.281415] Subtest engine-order: FAIL (14.621s)
[95.901929] [017/142] (919s left) kms_atomic (test-only)
[96.325812] Starting subtest: test-only
[97.229695] Subtest test-only: SUCCESS (0.905s)
[97.571761] [018/142] (918s left) gem_userptr_blits (invalid-mmap-offset-unsync)
[97.793768] Subtest invalid-mmap-offset-unsync: SKIP (0.000s)
[97.946842] [019/142] (917s left) gem_exec_parallel (userptr)
[98.177293] Starting subtest: userptr
[98.178884] Starting dynamic subtest: rcs0
[101.641339] Dynamic subtest rcs0: SUCCESS (3.465s)
[101.656961] Starting dynamic subtest: bcs0
[105.314820] Dynamic subtest bcs0: SUCCESS (3.673s)
[105.331697] Starting dynamic subtest: vcs0
[108.826324] Dynamic subtest vcs0: SUCCESS (3.512s)
[108.843623] Starting dynamic subtest: vecs0
[112.354457] Dynamic subtest vecs0: SUCCESS (3.528s)
[112.355834] Subtest userptr: SUCCESS (14.180s)
[112.549675] [020/142] (903s left) kms_flip (plain-flip-fb-recreate)
[112.833241] Starting subtest: plain-flip-fb-recreate
[112.837636] Starting dynamic subtest: A-DP1
[123.661031] Dynamic subtest A-DP1: SUCCESS (10.825s)
[123.677954] Starting dynamic subtest: B-DP1
[134.409878] Dynamic subtest B-DP1: SUCCESS (10.747s)
[134.426449] Starting dynamic subtest: C-DP1
[145.169395] Dynamic subtest C-DP1: SUCCESS (10.759s)
[145.171235] Subtest plain-flip-fb-recreate: SUCCESS (32.339s)
[145.581817] [021/142] (870s left) v3d/v3d_perfmon (create-two-perfmon)
[145.843652] Subtest create-two-perfmon: SKIP (0.000s)
[145.913498] [022/142] (870s left) kms_draw_crc (draw-method-mmap-cpu)
[146.217686] Starting subtest: draw-method-mmap-cpu
[146.219164] Starting dynamic subtest: xrgb8888-untiled
[146.508534] Dynamic subtest xrgb8888-untiled: SUCCESS (0.292s)
[146.525927] Starting dynamic subtest: rgb565-untiled
[146.775768] Dynamic subtest rgb565-untiled: SUCCESS (0.266s)
[146.791646] Starting dynamic subtest: xrgb2101010-untiled
[147.058602] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.283s)
[147.074359] Starting dynamic subtest: xrgb8888-xtiled
[147.241305] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.183s)
[147.257938] Starting dynamic subtest: rgb565-xtiled
[147.424278] Dynamic subtest rgb565-xtiled: SUCCESS (0.182s)
[147.440199] Starting dynamic subtest: xrgb2101010-xtiled
[147.607926] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.183s)
[147.623968] Starting dynamic subtest: xrgb8888-ytiled
[147.808586] Dynamic subtest xrgb8888-ytiled: SUCCESS (0.199s)
[147.824527] Starting dynamic subtest: rgb565-ytiled
[147.990988] Dynamic subtest rgb565-ytiled: SUCCESS (0.183s)
[148.007055] Starting dynamic subtest: xrgb2101010-ytiled
[148.190979] Dynamic subtest xrgb2101010-ytiled: SUCCESS (0.199s)
[148.192468] Subtest draw-method-mmap-cpu: SUCCESS (1.975s)
[148.295872] [023/142] (867s left) kms_vblank (pipe-c-query-forked)
[148.595916] Starting subtest: pipe-C-query-forked
[151.466305] Subtest pipe-C-query-forked: SUCCESS (2.873s)
[151.801780] [024/142] (864s left) drm_read (short-buffer-wakeup)
[152.231087] Starting subtest: short-buffer-wakeup
[184.447696] Subtest short-buffer-wakeup: SUCCESS (32.215s)
[184.567256] [025/142] (831s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-render)
[184.881326] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-render
[184.883952] Subtest fbcpsr-1p-offscren-pri-indfb-draw-render: SKIP (0.000s)
[184.987342] [026/142] (831s left) gem_ctx_persistence (legacy-engines-mixed)
[185.200547] Starting subtest: legacy-engines-mixed
[185.202179] Starting dynamic subtest: render
[185.245659] Dynamic subtest render: SUCCESS (0.045s)
[185.268733] Starting dynamic subtest: bsd
[185.298801] Dynamic subtest bsd: SUCCESS (0.052s)
[185.316959] Starting dynamic subtest: blt
[185.346452] Dynamic subtest blt: SUCCESS (0.047s)
[185.364048] Starting dynamic subtest: vebox
[185.398464] Dynamic subtest vebox: SUCCESS (0.051s)
[185.399952] Subtest legacy-engines-mixed: SUCCESS (0.199s)
[185.484822] [027/142] (830s left) kms_flip (2x-flip-vs-fences-interruptible)
[185.781957] Starting subtest: 2x-flip-vs-fences-interruptible
[185.786967] Subtest 2x-flip-vs-fences-interruptible: SKIP (0.003s)
[185.883989] [028/142] (830s left) syncobj_timeline (multi-wait-for-submit-available-submitted-signaled)
[186.091510] Starting subtest: multi-wait-for-submit-available-submitted-signaled
[186.095177] Subtest multi-wait-for-submit-available-submitted-signaled: SUCCESS (0.002s)
[186.183575] [029/142] (830s left) gem_exec_whisper (basic-fds-forked-all)
[186.415489] Starting subtest: basic-fds-forked-all
[210.478977] Subtest basic-fds-forked-all: SUCCESS (24.061s)
[210.683977] [030/142] (805s left) gem_softpin (allocator-fork)
[210.899783] Starting subtest: allocator-fork
[213.283173] Subtest allocator-fork: SUCCESS (2.380s)
[213.402604] [031/142] (802s left) gem_flink_basic (double-flink)
[213.606463] Starting subtest: double-flink
[213.608070] Subtest double-flink: SUCCESS (0.000s)
[213.700179] [032/142] (802s left) kms_ccs (pipe-b-ccs-on-another-bo-yf_tiled_ccs)
[214.238906] Starting subtest: pipe-B-ccs-on-another-bo-yf_tiled_ccs
[214.240558] Subtest pipe-B-ccs-on-another-bo-yf_tiled_ccs: SUCCESS (0.001s)
[214.685376] [033/142] (801s left) gem_huc_copy (huc-copy)
[214.898967] Starting subtest: huc-copy
[214.902397] Subtest huc-copy: SKIP (0.000s)
[215.002177] [034/142] (801s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-blt)
[215.314965] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-blt
[215.317731] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[215.419439] [035/142] (800s left) kms_properties (connector-properties-legacy)
[215.729188] Starting subtest: connector-properties-legacy
[218.017898] Subtest connector-properties-legacy: SUCCESS (2.289s)
[218.344954] [036/142] (798s left) kms_cursor_legacy (flip-vs-cursor-varying-size)
[218.644565] Starting subtest: flip-vs-cursor-varying-size
[237.649606] Subtest flip-vs-cursor-varying-size: SUCCESS (19.008s)
[237.989272] [037/142] (778s left) gem_ctx_param (invalid-get-no-zeromap)
[238.197118] Starting subtest: invalid-get-no-zeromap
[238.198419] Subtest invalid-get-no-zeromap: SUCCESS (0.000s)
[238.286387] [038/142] (778s left) kms_vblank (pipe-b-wait-forked-busy-hang)
[238.595248] Starting subtest: pipe-B-wait-forked-busy-hang
[245.992378] Subtest pipe-B-wait-forked-busy-hang: SUCCESS (7.399s)
[246.335409] [039/142] (770s left) i915_hangman (error-state-basic)
[246.566988] Starting subtest: error-state-basic
[246.583289] Subtest error-state-basic: SUCCESS (0.016s)
[246.668808] [040/142] (769s left) gem_exec_reloc (basic-wc-active)
[246.884340] Starting subtest: basic-wc-active
[247.027962] Subtest basic-wc-active: SUCCESS (0.142s)
[247.219086] [041/142] (769s left) kms_psr2_su (page_flip-p010)
[247.425990] Subtest page_flip-P010: SKIP (0.000s)
[247.484306] [042/142] (769s left) kms_prime (basic-crc-vgem)
[249.595928] Starting subtest: basic-crc-vgem
[249.597335] Starting dynamic subtest: second-to-first
[250.829461] Dynamic subtest second-to-first: SUCCESS (1.232s)
[250.830782] Subtest basic-crc-vgem: SUCCESS (1.234s)
[250.936968] [043/142] (765s left) gem_render_copy_redux (normal)
[251.176090] Starting subtest: normal
[253.389192] Subtest normal: SUCCESS (2.211s)
[253.501862] [044/142] (763s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[253.833055] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[253.836289] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[253.935929] [045/142] (762s left) gem_basic (multigpu-create-close)
[254.141542] Starting subtest: multigpu-create-close
[254.144804] Subtest multigpu-create-close: SKIP (0.000s)
[254.235364] [046/142] (762s left) syncobj_wait (multi-wait-all-submitted-signaled)
[254.444742] Starting subtest: multi-wait-all-submitted-signaled
[254.553752] Subtest multi-wait-all-submitted-signaled: SUCCESS (0.107s)
[254.649281] [047/142] (762s left) perf_pmu (init-wait)
[254.859617] Starting subtest: init-wait
[254.861089] Starting dynamic subtest: rcs0
[254.865370] Dynamic subtest rcs0: SUCCESS (0.002s)
[254.887501] Starting dynamic subtest: bcs0
[254.888748] Dynamic subtest bcs0: SUCCESS (0.002s)
[254.906646] Starting dynamic subtest: vcs0
[254.907965] Dynamic subtest vcs0: SUCCESS (0.001s)
[254.927451] Starting dynamic subtest: vecs0
[254.928847] Dynamic subtest vecs0: SUCCESS (0.001s)
[254.930145] Subtest init-wait: SUCCESS (0.013s)
[254.968163] [048/142] (761s left) kms_ccs (pipe-a-bad-pixel-format-4_tiled_mtl_mc_ccs)
[255.270408] Starting subtest: pipe-A-bad-pixel-format-4_tiled_mtl_mc_ccs
[255.273060] Subtest pipe-A-bad-pixel-format-4_tiled_mtl_mc_ccs: SKIP (0.000s)
[255.950273] [049/142] (761s left) kms_big_fb (y-tiled-64bpp-rotate-90)
[256.282676] Starting subtest: y-tiled-64bpp-rotate-90
[256.368475] Subtest y-tiled-64bpp-rotate-90: SKIP (0.083s)
[256.483953] [050/142] (760s left) kms_color (invalid-gamma-lut-sizes)
[256.795120] Starting subtest: invalid-gamma-lut-sizes
[256.796956] Starting dynamic subtest: pipe-A
[256.894081] Dynamic subtest pipe-A: SUCCESS (0.100s)
[256.915906] Starting dynamic subtest: pipe-B
[256.917989] Dynamic subtest pipe-B: SUCCESS (0.007s)
[256.943884] Starting dynamic subtest: pipe-C
[256.945806] Dynamic subtest pipe-C: SUCCESS (0.007s)
[256.947773] Subtest invalid-gamma-lut-sizes: SUCCESS (0.119s)
[257.231334] [051/142] (759s left) gem_pipe_control_store_loop (fresh-buffer)
[257.444052] Starting subtest: fresh-buffer
[259.602409] Subtest fresh-buffer: SUCCESS (2.156s)
[259.714230] [052/142] (757s left) v3d/v3d_submit_csd (bad-multisync-pad)
[259.975631] Subtest bad-multisync-pad: SKIP (0.000s)
[260.047316] [053/142] (757s left) gem_eio (wait-10ms)
[260.274169] Starting subtest: wait-10ms
[260.383201] Subtest wait-10ms: SUCCESS (0.109s)
[260.584997] [054/142] (756s left) kms_cursor_edge_walk (256x256-right-edge)
[260.903629] Starting subtest: 256x256-right-edge
[260.904898] Starting dynamic subtest: pipe-A-DP-1
[264.537632] Dynamic subtest pipe-A-DP-1: SUCCESS (3.633s)
[264.557387] Starting dynamic subtest: pipe-C-DP-1
[268.063700] Dynamic subtest pipe-C-DP-1: SUCCESS (3.522s)
[268.065021] Subtest 256x256-right-edge: SUCCESS (7.163s)
[268.391316] [055/142] (748s left) gem_exec_schedule (submit-late-slice)
[268.629598] Starting subtest: submit-late-slice
[268.631289] Starting dynamic subtest: rcs0
[268.695906] Dynamic subtest rcs0: SUCCESS (0.063s)
[268.726159] Starting dynamic subtest: bcs0
[268.749773] Dynamic subtest bcs0: SUCCESS (0.054s)
[268.773247] Starting dynamic subtest: vcs0
[268.802413] Dynamic subtest vcs0: SUCCESS (0.051s)
[268.826953] Starting dynamic subtest: vecs0
[268.854414] Dynamic subtest vecs0: SUCCESS (0.050s)
[268.855892] Subtest submit-late-slice: SUCCESS (0.226s)
[269.205977] [056/142] (748s left) kms_universal_plane (universal-plane-pageflip-windowed-pipe-b)
[269.506802] Starting subtest: universal-plane-pageflip-windowed-pipe-B
[270.309894] Subtest universal-plane-pageflip-windowed-pipe-B: SUCCESS (0.806s)
[270.627631] [057/142] (746s left) kms_cursor_legacy (cursora-vs-flipb-toggle)
[270.930661] Starting subtest: cursorA-vs-flipB-toggle
[270.933182] Subtest cursorA-vs-flipB-toggle: SKIP (0.002s)
[271.028460] [058/142] (746s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-wc)
[271.346705] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-wc
[271.349561] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[271.445321] [059/142] (745s left) kms_cdclk (plane-scaling)
[271.650379] Subtest plane-scaling: SKIP (0.000s)
[271.713563] [060/142] (745s left) gem_exec_reloc (basic-wc)
[271.926449] Starting subtest: basic-wc
[271.947328] Subtest basic-wc: SUCCESS (0.021s)
[272.127522] [061/142] (745s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[272.335346] Starting subtest: addfb25-framebuffer-vs-set-tiling
[272.336797] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[272.544147] [062/142] (744s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-move)
[272.865890] Starting subtest: fbc-1p-primscrn-spr-indfb-move
[275.805188] Subtest fbc-1p-primscrn-spr-indfb-move: SUCCESS (2.940s)
[275.943627] [063/142] (741s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[276.252432] Starting subtest: cursorB-vs-flipA-toggle
[276.255430] Subtest cursorB-vs-flipA-toggle: SKIP (0.005s)
[276.344588] [064/142] (741s left) kms_addfb_basic (bad-pitch-0)
[276.551324] Starting subtest: bad-pitch-0
[276.552602] Subtest bad-pitch-0: SUCCESS (0.000s)
[276.744617] [065/142] (740s left) kms_vblank (pipe-b-wait-busy)
[277.046184] Starting subtest: pipe-B-wait-busy
[279.928304] Subtest pipe-B-wait-busy: SUCCESS (2.885s)
[280.267910] [066/142] (737s left) gem_render_copy (y-tiled-ccs-to-y-tiled)
[280.496081] Starting subtest: y-tiled-ccs-to-y-tiled
[280.497755] Starting dynamic subtest: smem
[280.793281] Dynamic subtest smem: SUCCESS (0.294s)
[280.794605] Subtest y-tiled-ccs-to-y-tiled: SUCCESS (0.296s)
[281.002679] [067/142] (736s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[281.208930] Starting subtest: single-wait-all-for-submit-available-submitted
[281.210647] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.001s)
[281.300694] [068/142] (736s left) kms_ccs (pipe-b-crc-primary-rotation-180-4_tiled_dg2_rc_ccs)
[281.801773] Starting subtest: pipe-B-crc-primary-rotation-180-4_tiled_dg2_rc_ccs
[281.804746] Subtest pipe-B-crc-primary-rotation-180-4_tiled_dg2_rc_ccs: SKIP (0.001s)
[282.289120] [069/142] (735s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset)
[282.575632] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset
[282.578223] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset: SKIP (0.003s)
[282.689994] [070/142] (735s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[283.009096] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-gtt
[283.011676] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[283.105431] [071/142] (734s left) kms_chamelium_hpd (dp-hpd-enable-disable-mode)
[283.501410] Subtest dp-hpd-enable-disable-mode: SKIP (0.000s)
[283.777275] [072/142] (734s left) kms_ccs (pipe-a-crc-sprite-planes-basic-4_tiled_mtl_rc_ccs_cc)
[284.236678] Starting subtest: pipe-A-crc-sprite-planes-basic-4_tiled_mtl_rc_ccs_cc
[284.241177] Subtest pipe-A-crc-sprite-planes-basic-4_tiled_mtl_rc_ccs_cc: SKIP (0.005s)
[284.754231] [073/142] (733s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[284.959822] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[284.961492] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[285.053694] [074/142] (732s left) kms_frontbuffer_tracking (fbcpsr-tiling-4)
[285.370493] Starting subtest: fbcpsr-tiling-4
[285.373637] Subtest fbcpsr-tiling-4: SKIP (0.000s)
[285.471125] [075/142] (732s left) kms_addfb_basic (basic-y-tiled-legacy)
[285.678199] Starting subtest: basic-y-tiled-legacy
[285.679794] Subtest basic-y-tiled-legacy: SUCCESS (0.001s)
[285.887425] [076/142] (732s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted-signa
[286.095292] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled
[286.201427] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.105s)
[286.287254] [077/142] (731s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[286.625796] Starting subtest: cursor-rapid-movement-max-size
[286.629992] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[286.737802] [078/142] (731s left) kms_cursor_legacy (2x-cursor-vs-flip-legacy)
[287.039280] Starting subtest: 2x-cursor-vs-flip-legacy
[287.042371] Subtest 2x-cursor-vs-flip-legacy: SKIP (0.004s)
[287.120578] [079/142] (731s left) gem_workarounds (reset-context)
[287.356040] Starting subtest: reset-context
[287.382834] Subtest reset-context: SUCCESS (0.026s)
[287.487414] [080/142] (730s left) gem_exec_schedule (noreorder)
[287.853386] Starting subtest: noreorder
[287.854830] Starting dynamic subtest: rcs0
[287.905077] Dynamic subtest rcs0: SUCCESS (0.050s)
[287.928555] Starting dynamic subtest: bcs0
[287.968886] Dynamic subtest bcs0: SUCCESS (0.062s)
[287.997336] Starting dynamic subtest: vcs0
[288.022997] Dynamic subtest vcs0: SUCCESS (0.052s)
[288.051444] Starting dynamic subtest: vecs0
[288.083658] Dynamic subtest vecs0: SUCCESS (0.059s)
[288.085249] Subtest noreorder: SUCCESS (0.232s)
[288.288752] [081/142] (729s left) kms_plane_lowres (tiling-4)
[288.588119] Starting subtest: tiling-4
[288.590655] Subtest tiling-4: SKIP (0.000s)
[288.670395] [082/142] (729s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-5)
[288.981100] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-5
[288.982698] Starting dynamic subtest: pipe-A-DP-1
[289.295428] Dynamic subtest pipe-A-DP-1: SUCCESS (0.313s)
[289.335334] Starting dynamic subtest: pipe-B-DP-1
[289.671727] Dynamic subtest pipe-B-DP-1: SUCCESS (0.372s)
[289.695429] Starting dynamic subtest: pipe-C-DP-1
[289.768627] Dynamic subtest pipe-C-DP-1: SKIP (0.089s)
[289.770194] Subtest planes-upscale-factor-0-25-downscale-factor-0-5: SUCCESS (0.788s)
[290.163051] [083/142] (728s left) kms_scaling_modes (scaling-mode-full-aspect)
[290.471647] Starting subtest: scaling-mode-full-aspect
[290.474316] Subtest scaling-mode-full-aspect: SKIP (0.001s)
[290.562877] [084/142] (727s left) v3d/v3d_wait_bo (unused-bo-1ns)
[290.823601] Subtest unused-bo-1ns: SKIP (0.000s)
[290.895618] [085/142] (727s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[291.217650] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu
[291.221379] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[291.314320] [086/142] (727s left) i915_pm_backlight (basic-brightness)
[291.622390] Subtest basic-brightness: SKIP (0.000s)
[291.679650] [087/142] (726s left) kms_ccs (pipe-b-bad-pixel-format-4_tiled_dg2_rc_ccs_cc)
[292.160542] Starting subtest: pipe-B-bad-pixel-format-4_tiled_dg2_rc_ccs_cc
[292.163271] Subtest pipe-B-bad-pixel-format-4_tiled_dg2_rc_ccs_cc: SKIP (0.000s)
[292.707637] [088/142] (725s left) gem_ctx_persistence (process)
[292.923701] Starting subtest: process
[293.046994] Subtest process: SUCCESS (0.121s)
[293.141455] [089/142] (725s left) kms_big_fb (x-tiled-addfb-size-offset-overflow)
[293.469418] Starting subtest: x-tiled-addfb-size-offset-overflow
[293.472557] Subtest x-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[293.574255] [090/142] (725s left) gem_exec_fence (submit)
[293.937281] Starting subtest: submit
[293.938818] Starting dynamic subtest: rcs0
[293.960411] Dynamic subtest rcs0: SUCCESS (0.023s)
[293.989339] Starting dynamic subtest: bcs0
[293.991156] Dynamic subtest bcs0: SUCCESS (0.025s)
[294.015276] Starting dynamic subtest: vcs0
[294.016659] Dynamic subtest vcs0: SUCCESS (0.023s)
[294.040477] Starting dynamic subtest: vecs0
[294.042463] Dynamic subtest vecs0: SUCCESS (0.021s)
[294.043783] Subtest submit: SUCCESS (0.104s)
[294.708188] [091/142] (723s left) kms_cursor_legacy (nonblocking-modeset-vs-cursor-atomic)
[295.020799] Starting subtest: nonblocking-modeset-vs-cursor-atomic
[295.627451] Subtest nonblocking-modeset-vs-cursor-atomic: SUCCESS (0.607s)
[295.971993] [092/142] (722s left) gem_exec_balancer (bonded-false-hang)
[300.027932] Starting subtest: bonded-false-hang
[303.611407] Subtest bonded-false-hang: SUCCESS (3.583s)
[303.703911] [093/142] (714s left) kms_hdr (static-toggle-dpms)
[304.008935] Starting subtest: static-toggle-dpms
[304.011616] Subtest static-toggle-dpms: SKIP (0.000s)
[304.103723] [094/142] (714s left) gem_exec_balancer (nop)
[304.329956] Starting subtest: nop
[344.223954] Subtest nop: SUCCESS (39.895s)
[348.153518] [095/142] (670s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-wc)
[348.465263] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-wc
[348.468142] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[348.570522] [096/142] (670s left) perf (non-sampling-read-error)
[348.918840] Starting subtest: non-sampling-read-error
[348.972419] Subtest non-sampling-read-error: SUCCESS (0.054s)
[349.018980] [097/142] (669s left) syncobj_timeline (invalid-wait-zero-handles)
[349.232182] Starting subtest: invalid-wait-zero-handles
[349.233539] Subtest invalid-wait-zero-handles: SUCCESS (0.000s)
[349.319501] [098/142] (669s left) kms_vblank (pipe-d-ts-continuation-idle)
[349.628570] Subtest pipe-D-ts-continuation-idle: SKIP (0.000s)
[349.721757] [099/142] (669s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-downscaling)
[350.047574] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-downscaling
[350.048981] Starting dynamic subtest: pipe-A-valid-mode
[350.148636] Dynamic subtest pipe-A-valid-mode: SKIP (0.098s)
[350.151592] Subtest flip-64bpp-yftile-to-16bpp-yftile-downscaling: SKIP (0.102s)
[350.463829] [100/142] (668s left) kms_atomic_interruptible (legacy-dpms)
[350.773586] Starting subtest: legacy-dpms
[350.777677] Starting dynamic subtest: DP-1-pipe-A
[357.365545] Dynamic subtest DP-1-pipe-A: SUCCESS (6.584s)
[357.367111] Subtest legacy-dpms: SUCCESS (6.589s)
[357.711372] [101/142] (661s left) vc4/vc4_perfmon (create-perfmon-invalid-events)
[357.986553] Subtest create-perfmon-invalid-events: SKIP (0.000s)
[358.059682] [102/142] (660s left) perf (global-sseu-config)
[358.355501] Starting subtest: global-sseu-config
[358.357089] Starting dynamic subtest: 0-rcs0
[358.468642] Dynamic subtest 0-rcs0: SUCCESS (0.111s)
[358.473209] Subtest global-sseu-config: SUCCESS (0.115s)
[358.527791] [103/142] (660s left) gem_render_copy (y-tiled-ccs-to-yf-tiled-mc-ccs)
[358.752815] Starting subtest: y-tiled-ccs-to-yf-tiled-mc-ccs
[358.755724] Subtest y-tiled-ccs-to-yf-tiled-mc-ccs: SKIP (0.000s)
[358.959743] [104/142] (660s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite)
[359.279180] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite
[359.281988] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[359.393787] [105/142] (659s left) kms_rotation_crc (primary-y-tiled-reflect-x-0)
[359.695702] Starting subtest: primary-y-tiled-reflect-x-0
[359.698202] Subtest primary-y-tiled-reflect-x-0: SKIP (0.003s)
[359.793117] [106/142] (659s left) gem_wait (write-wait)
[360.036980] Starting subtest: write-wait
[360.038641] Starting dynamic subtest: all-engines
[361.076942] Dynamic subtest all-engines: SUCCESS (1.039s)
[361.100918] Starting dynamic subtest: rcs0
[362.120236] Dynamic subtest rcs0: SUCCESS (1.039s)
[362.142879] Starting dynamic subtest: bcs0
[363.158554] Dynamic subtest bcs0: SUCCESS (1.035s)
[363.181160] Starting dynamic subtest: vcs0
[364.192387] Dynamic subtest vcs0: SUCCESS (1.031s)
[364.214708] Starting dynamic subtest: vecs0
[365.228746] Dynamic subtest vecs0: SUCCESS (1.033s)
[365.230462] Subtest write-wait: SUCCESS (5.193s)
[365.477178] [107/142] (653s left) gem_lmem_swapping (verify-random-ccs)
[365.693517] Subtest verify-random-ccs: SKIP (0.000s)
[365.741312] [108/142] (653s left) syncobj_wait (invalid-signal-bad-pad)
[365.946432] Starting subtest: invalid-signal-bad-pad
[365.948158] Subtest invalid-signal-bad-pad: SUCCESS (0.000s)
[366.041837] [109/142] (653s left) i915_pm_rpm (modeset-lpsp)
[367.873039] Starting subtest: modeset-lpsp
[368.399369] Subtest modeset-lpsp: SKIP (0.523s)
[368.883391] [110/142] (650s left) vgem_basic (bad-pad)
[369.092379] Starting subtest: bad-pad
[369.093653] Subtest bad-pad: SUCCESS (0.000s)
[369.164888] [111/142] (650s left) gem_ccs (block-multicopy-compressed)
[369.380371] Subtest block-multicopy-compressed: SKIP (0.000s)
[369.431068] [112/142] (650s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[369.742224] Starting subtest: extended-pageflip-modeset-hang-oldfb
[369.744238] Starting dynamic subtest: pipe-A
[373.989632] Dynamic subtest pipe-A: SUCCESS (4.244s)
[374.011850] Starting dynamic subtest: pipe-C
[377.947265] Dynamic subtest pipe-C: SUCCESS (3.953s)
[377.948758] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (8.210s)
[378.311900] [113/142] (641s left) gem_exec_create (legacy)
[378.544909] Starting subtest: legacy
[378.546187] Starting dynamic subtest: smem
[380.609268] Dynamic subtest smem: SUCCESS (2.065s)
[380.610958] Subtest legacy: SUCCESS (2.068s)
[380.807975] [114/142] (638s left) kms_concurrent (pipe-a)
[381.113656] Starting subtest: pipe-A
[381.117661] Starting dynamic subtest: DP-1
[381.893089] Dynamic subtest DP-1: SUCCESS (0.774s)
[381.894707] Subtest pipe-A: SUCCESS (0.779s)
[382.215451] [115/142] (637s left) kms_ccs (pipe-d-bad-pixel-format-y_tiled_gen12_mc_ccs)
[382.899107] Subtest pipe-D-bad-pixel-format-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[383.217799] [116/142] (636s left) kms_ccs (pipe-c-bad-rotation-90-yf_tiled_ccs)
[383.814096] Starting subtest: pipe-C-bad-rotation-90-yf_tiled_ccs
[383.816805] Subtest pipe-C-bad-rotation-90-yf_tiled_ccs: SKIP (0.000s)
[384.225915] [117/142] (635s left) gem_pxp (protected-raw-src-copy-not-readible)
[386.952057] Subtest protected-raw-src-copy-not-readible: SKIP (0.000s)
[387.042198] [118/142] (632s left) gem_pipe_control_store_loop (reused-buffer)
[387.254597] Starting subtest: reused-buffer
[389.414391] Subtest reused-buffer: SUCCESS (2.157s)
[389.525346] [119/142] (630s left) kms_psr (primary_blt)
[389.731471] Subtest primary_blt: SKIP (0.000s)
[389.791753] [120/142] (629s left) perf (gen8-unprivileged-single-ctx-counters)
[390.086626] Starting subtest: gen8-unprivileged-single-ctx-counters
[390.290781] Subtest gen8-unprivileged-single-ctx-counters: SUCCESS (0.203s)
[390.343774] [121/142] (629s left) api_intel_allocator (reopen-fork)
[390.552303] Starting subtest: reopen-fork
[393.828881] Subtest reopen-fork: SUCCESS (3.277s)
[393.928687] [122/142] (625s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc)
[394.251331] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc
[394.254179] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[394.359877] [123/142] (625s left) kms_ccs (pipe-d-crc-primary-rotation-180-4_tiled_mtl_rc_ccs_cc)
[395.027520] Subtest pipe-D-crc-primary-rotation-180-4_tiled_mtl_rc_ccs_cc: SKIP (0.000s)
[395.346785] [124/142] (624s left) kms_ccs (pipe-c-random-ccs-data-yf_tiled_ccs)
[395.952129] Starting subtest: pipe-C-random-ccs-data-yf_tiled_ccs
[395.955979] Subtest pipe-C-random-ccs-data-yf_tiled_ccs: SKIP (0.000s)
[396.316732] [125/142] (623s left) kms_ccs (pipe-b-missing-ccs-buffer-y_tiled_ccs)
[396.856922] Starting subtest: pipe-B-missing-ccs-buffer-y_tiled_ccs
[396.858344] Subtest pipe-B-missing-ccs-buffer-y_tiled_ccs: SUCCESS (0.001s)
[397.318798] [126/142] (622s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-blt)
[397.642925] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-blt
[397.646087] Subtest psr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[397.752150] [127/142] (622s left) gem_eio (in-flight-internal-immediate)
[397.976611] Starting subtest: in-flight-internal-immediate
[398.058820] Subtest in-flight-internal-immediate: SUCCESS (0.082s)
[398.252124] [128/142] (621s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[398.464628] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[398.466371] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[398.551984] [129/142] (621s left) kms_plane_scaling (plane-downscale-with-modifiers-factor-0-75)
[398.862465] Starting subtest: plane-downscale-with-modifiers-factor-0-75
[398.863812] Starting dynamic subtest: pipe-A-DP-1
[400.042177] Dynamic subtest pipe-A-DP-1: SUCCESS (1.181s)
[400.065444] Starting dynamic subtest: pipe-B-DP-1
[401.515332] Dynamic subtest pipe-B-DP-1: SUCCESS (1.468s)
[401.540449] Starting dynamic subtest: pipe-C-DP-1
[402.589041] Dynamic subtest pipe-C-DP-1: SUCCESS (1.068s)
[402.590760] Subtest plane-downscale-with-modifiers-factor-0-75: SUCCESS (3.733s)
[403.020952] [130/142] (617s left) kms_ccs (pipe-c-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc)
[403.687823] Starting subtest: pipe-C-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc
[403.690315] Subtest pipe-C-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc: SKIP (0.000s)
[404.037066] [131/142] (616s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-msflip-blt)
[404.347902] Starting subtest: fbc-2p-primscrn-shrfb-msflip-blt
[404.350663] Subtest fbc-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[404.453382] [132/142] (615s left) api_intel_bb (purge-bb)
[404.664197] Starting subtest: purge-bb
[404.668304] Subtest purge-bb: SUCCESS (0.005s)
[404.787631] [133/142] (615s left) kms_ccs (pipe-a-crc-primary-basic-y_tiled_gen12_rc_ccs)
[405.210288] Starting subtest: pipe-A-crc-primary-basic-y_tiled_gen12_rc_ccs
[405.212935] Subtest pipe-A-crc-primary-basic-y_tiled_gen12_rc_ccs: SKIP (0.002s)
[405.809401] [134/142] (614s left) kms_vblank (pipe-c-ts-continuation-idle-hang)
[406.117110] Starting subtest: pipe-C-ts-continuation-idle-hang
[408.039318] Subtest pipe-C-ts-continuation-idle-hang: SUCCESS (1.922s)
[408.386967] [135/142] (612s left) gem_mmap_offset (oob-read)
[408.595579] Starting subtest: oob-read
[408.597569] Subtest oob-read: SUCCESS (0.002s)
[408.702918] [136/142] (611s left) kms_lease (lease_invalid_connector)
[409.011940] Starting subtest: lease_invalid_connector
[409.013334] Starting dynamic subtest: pipe-A-DP-1
[409.014667] Dynamic subtest pipe-A-DP-1: SUCCESS (0.000s)
[409.036516] Starting dynamic subtest: pipe-B-DP-1
[409.037821] Dynamic subtest pipe-B-DP-1: SUCCESS (0.000s)
[409.056346] Starting dynamic subtest: pipe-C-DP-1
[409.057621] Dynamic subtest pipe-C-DP-1: SUCCESS (0.000s)
[409.058914] Subtest lease_invalid_connector: SUCCESS (0.012s)
[409.119430] [137/142] (611s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling)
[409.428500] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling
[409.429937] Starting dynamic subtest: pipe-A-valid-mode
[409.554284] Dynamic subtest pipe-A-valid-mode: SKIP (0.123s)
[409.557043] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling: SKIP (0.128s)
[409.896519] [138/142] (610s left) kms_cursor_crc (cursor-size-change)
[410.210304] Starting subtest: cursor-size-change
[410.214755] Starting dynamic subtest: pipe-A-DP-1
[410.951474] Dynamic subtest pipe-A-DP-1: SUCCESS (0.737s)
[410.979255] Starting dynamic subtest: pipe-C-DP-1
[412.019723] Dynamic subtest pipe-C-DP-1: SUCCESS (1.064s)
[412.021651] Subtest cursor-size-change: SUCCESS (1.812s)
[412.388897] [139/142] (608s left) i915_pm_rpm (drm-resources-equal)
[414.209309] Starting subtest: drm-resources-equal
[415.911171] Subtest drm-resources-equal: SUCCESS (1.704s)
[416.134938] [140/142] (604s left) kms_big_fb (linear-16bpp-rotate-0)
[416.463446] Starting subtest: linear-16bpp-rotate-0
[420.989945] Subtest linear-16bpp-rotate-0: SUCCESS (4.527s)
[421.318751] [141/142] (599s left) gem_wait (invalid-buf)
[421.540665] Starting subtest: invalid-buf
[421.542050] Subtest invalid-buf: SUCCESS (0.000s)
[421.819424] [142/142] (598s left) kms_ccs (pipe-c-crc-primary-rotation-180-y_tiled_gen12_rc_ccs_cc)
[422.432371] Starting subtest: pipe-C-crc-primary-rotation-180-y_tiled_gen12_rc_ccs_cc
[422.437564] Subtest pipe-C-crc-primary-rotation-180-y_tiled_gen12_rc_ccs_cc: SKIP (0.002s)
[422.830156] Closing watchdogs
Done.