[52.828400] Initializing watchdogs
[52.828639]   /dev/watchdog0
[52.845502] [001/141] (960s left) gem_exec_schedule (pi-userfault)
[53.258096] Starting subtest: pi-userfault
[53.260442] Starting dynamic subtest: rcs0
[53.286910] Dynamic subtest rcs0: SUCCESS (0.029s)
[53.311623] Starting dynamic subtest: bcs0
[53.313870] Dynamic subtest bcs0: SUCCESS (0.017s)
[53.332586] Starting dynamic subtest: vcs0
[53.335118] Dynamic subtest vcs0: SUCCESS (0.011s)
[53.349653] Starting dynamic subtest: vecs0
[53.352342] Dynamic subtest vecs0: SUCCESS (0.012s)
[53.354461] Subtest pi-userfault: SUCCESS (0.071s)
[53.549945] [002/141] (959s left) kms_ccs (pipe-b-random-ccs-data-4_tiled_dg2_rc_ccs)
[54.108347] Starting subtest: pipe-B-random-ccs-data-4_tiled_dg2_rc_ccs
[54.114523] Subtest pipe-B-random-ccs-data-4_tiled_dg2_rc_ccs: SKIP (0.000s)
[54.619975] [003/141] (958s left) gem_userptr_blits (stress-mm-invalidate-close-overlap)
[54.871535] Starting subtest: stress-mm-invalidate-close-overlap
[57.022298] Subtest stress-mm-invalidate-close-overlap: SUCCESS (2.149s)
[57.157573] [004/141] (956s left) api_intel_bb (bb-with-allocator)
[57.368387] Starting subtest: bb-with-allocator
[57.377633] Subtest bb-with-allocator: SUCCESS (0.010s)
[57.488733] [005/141] (956s left) kms_ccs (pipe-d-bad-aux-stride-4_tiled_mtl_mc_ccs)
[58.210206] Subtest pipe-D-bad-aux-stride-4_tiled_mtl_mc_ccs: SKIP (0.000s)
[58.514869] [006/141] (955s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-gtt)
[58.832340] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-gtt
[58.836909] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[58.914683] [007/141] (954s left) kms_cursor_legacy (flip-vs-cursor-varying-size)
[59.218376] Starting subtest: flip-vs-cursor-varying-size
[78.778101] Subtest flip-vs-cursor-varying-size: SUCCESS (19.562s)
[79.103829] [008/141] (934s left) kms_vblank (pipe-b-ts-continuation-idle-hang)
[79.421362] Starting subtest: pipe-B-ts-continuation-idle-hang
[86.962966] Subtest pipe-B-ts-continuation-idle-hang: SUCCESS (7.543s)
[87.268803] [009/141] (926s left) kms_vblank (pipe-b-query-idle)
[87.574471] Starting subtest: pipe-B-query-idle
[90.218365] Subtest pipe-B-query-idle: SUCCESS (2.644s)
[90.549436] [010/141] (923s left) kms_ccs (pipe-a-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs)
[91.037572] Starting subtest: pipe-A-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs
[91.045766] Subtest pipe-A-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs: SKIP (0.006s)
[91.621215] [011/141] (922s left) kms_vblank (pipe-d-ts-continuation-suspend)
[91.927075] Subtest pipe-D-ts-continuation-suspend: SKIP (0.000s)
[92.005134] [012/141] (921s left) syncobj_wait (invalid-wait-zero-handles)
[92.213300] Starting subtest: invalid-wait-zero-handles
[92.215654] Subtest invalid-wait-zero-handles: SUCCESS (0.000s)
[92.287666] [013/141] (921s left) kms_big_fb (4-tiled-8bpp-rotate-180)
[92.619474] Starting subtest: 4-tiled-8bpp-rotate-180
[92.623488] Subtest 4-tiled-8bpp-rotate-180: SKIP (0.000s)
[92.721447] [014/141] (921s left) gem_ctx_exec (basic-norecovery)
[92.930588] Starting subtest: basic-norecovery
[92.965709] Subtest basic-norecovery: SUCCESS (0.037s)
[93.070435] [015/141] (920s left) gem_mmap_gtt (basic-small-copy)
[93.274526] Starting subtest: basic-small-copy
[96.993289] Subtest basic-small-copy: SUCCESS (3.720s)
[97.074074] [016/141] (916s left) syncobj_timeline (reset-during-wait-for-submit)
[97.411587] Starting subtest: reset-during-wait-for-submit
[97.520742] Subtest reset-during-wait-for-submit: SUCCESS (0.109s)
[97.605897] [017/141] (916s left) kms_ccs (pipe-b-bad-pixel-format-y_tiled_gen12_rc_ccs)
[98.114389] Starting subtest: pipe-B-bad-pixel-format-y_tiled_gen12_rc_ccs
[98.118862] Subtest pipe-B-bad-pixel-format-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[98.644629] [018/141] (915s left) kms_ccs (pipe-d-missing-ccs-buffer-y_tiled_ccs)
[99.361341] Subtest pipe-D-missing-ccs-buffer-y_tiled_ccs: SKIP (0.000s)
[99.665688] [019/141] (914s left) vc4/vc4_wait_bo (used-bo)
[99.931313] Subtest used-bo: SKIP (0.000s)
[99.999438] [020/141] (914s left) kms_ccs (pipe-c-crc-primary-basic-4_tiled_mtl_rc_ccs)
[100.625288] Starting subtest: pipe-C-crc-primary-basic-4_tiled_mtl_rc_ccs
[100.629361] Subtest pipe-C-crc-primary-basic-4_tiled_mtl_rc_ccs: SKIP (0.001s)
[101.023815] [021/141] (913s left) kms_universal_plane (universal-plane-pageflip-windowed-pipe-d)
[101.336438] Subtest universal-plane-pageflip-windowed-pipe-D: SKIP (0.000s)
[101.407210] [022/141] (912s left) gem_exec_suspend (basic-s0)
[101.640865] Starting subtest: basic-S0
[101.643233] Starting dynamic subtest: smem
[121.942485] Dynamic subtest smem: SUCCESS (20.301s)
[121.944639] Subtest basic-S0: SUCCESS (20.301s)
[125.143345] [023/141] (892s left) gem_exec_schedule (implicit-read-write)
[125.382855] Starting subtest: implicit-read-write
[125.385273] Starting dynamic subtest: rcs0
[125.534799] Dynamic subtest rcs0: SUCCESS (0.151s)
[125.558901] Starting dynamic subtest: bcs0
[125.689123] Dynamic subtest bcs0: SUCCESS (0.153s)
[125.713162] Starting dynamic subtest: vcs0
[125.819377] Dynamic subtest vcs0: SUCCESS (0.131s)
[125.843019] Starting dynamic subtest: vecs0
[125.955331] Dynamic subtest vecs0: SUCCESS (0.134s)
[125.957705] Subtest implicit-read-write: SUCCESS (0.570s)
[126.321148] [024/141] (890s left) i915_suspend (fence-restore-tiled2untiled)
[126.528342] Starting subtest: fence-restore-tiled2untiled
[145.730183] Subtest fence-restore-tiled2untiled: SUCCESS (4.126s)
[149.861722] [025/141] (886s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted-submitted)
[150.073460] Starting subtest: multi-wait-all-for-submit-unsubmitted-submitted
[150.176358] Subtest multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.103s)
[150.269843] [026/141] (886s left) perf_pmu (busy-check-all)
[150.483398] Starting subtest: busy-check-all
[150.485684] Starting dynamic subtest: rcs0
[151.016758] Dynamic subtest rcs0: SUCCESS (0.534s)
[151.038863] Starting dynamic subtest: bcs0
[151.553422] Dynamic subtest bcs0: SUCCESS (0.536s)
[151.575193] Starting dynamic subtest: vcs0
[152.091354] Dynamic subtest vcs0: SUCCESS (0.538s)
[152.112772] Starting dynamic subtest: vecs0
[152.625509] Dynamic subtest vecs0: SUCCESS (0.533s)
[152.628116] Subtest busy-check-all: SUCCESS (2.142s)
[152.721498] [027/141] (883s left) kms_rotation_crc (primary-rotation-90)
[153.026315] Starting subtest: primary-rotation-90
[159.166583] Subtest primary-rotation-90: SUCCESS (6.139s)
[159.574785] [028/141] (876s left) kms_vblank (pipe-a-wait-forked-busy-hang)
[159.877453] Starting subtest: pipe-A-wait-forked-busy-hang
[167.061268] Subtest pipe-A-wait-forked-busy-hang: SUCCESS (7.182s)
[167.404731] [029/141] (869s left) kms_addfb_basic (basic)
[167.613084] Starting subtest: basic
[167.615237] Subtest basic: SUCCESS (0.000s)
[167.819838] [030/141] (868s left) kms_frontbuffer_tracking (psr-suspend)
[168.134557] Starting subtest: psr-suspend
[168.138903] Subtest psr-suspend: SKIP (0.000s)
[168.219714] [031/141] (868s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-blt)
[168.534682] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-blt
[168.538690] Subtest psr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[168.636542] [032/141] (867s left) kms_big_fb (linear-8bpp-rotate-270)
[168.963099] Starting subtest: linear-8bpp-rotate-270
[169.045041] Subtest linear-8bpp-rotate-270: SKIP (0.079s)
[169.153157] [033/141] (867s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[169.479002] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip
[169.514876] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.034s)
[169.636475] [034/141] (867s left) v3d/v3d_submit_cl (single-in-sync)
[169.879216] Subtest single-in-sync: SKIP (0.000s)
[169.952878] [035/141] (866s left) kms_ccs (pipe-a-bad-pixel-format-4_tiled_dg2_rc_ccs_cc)
[170.254522] Starting subtest: pipe-A-bad-pixel-format-4_tiled_dg2_rc_ccs_cc
[170.258503] Subtest pipe-A-bad-pixel-format-4_tiled_dg2_rc_ccs_cc: SKIP (0.000s)
[170.979559] [036/141] (865s left) kms_cursor_crc (cursor-random-256x256)
[171.293417] Starting subtest: cursor-random-256x256
[171.307778] Starting dynamic subtest: pipe-A-DP-1
[175.287340] Dynamic subtest pipe-A-DP-1: SUCCESS (3.981s)
[175.374309] Starting dynamic subtest: pipe-C-DP-1
[179.470630] Dynamic subtest pipe-C-DP-1: SUCCESS (4.115s)
[179.475432] Subtest cursor-random-256x256: SUCCESS (8.176s)
[179.831621] [037/141] (856s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-plflip-blt)
[180.163463] Starting subtest: fbcpsr-1p-primscrn-indfb-plflip-blt
[180.167941] Subtest fbcpsr-1p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[180.260763] [038/141] (856s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-render)
[180.585717] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-render
[180.589718] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[180.677805] [039/141] (856s left) kms_flip (2x-flip-vs-panning-vs-hang)
[180.991356] Starting subtest: 2x-flip-vs-panning-vs-hang
[180.995452] Subtest 2x-flip-vs-panning-vs-hang: SKIP (0.003s)
[181.114100] [040/141] (855s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[181.436198] Starting subtest: flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[181.441199] Starting dynamic subtest: pipe-A-valid-mode
[181.540122] Dynamic subtest pipe-A-valid-mode: SKIP (0.104s)
[181.544526] Subtest flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.107s)
[181.886256] [041/141] (855s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[182.204154] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-gtt
[182.208160] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[182.311174] [042/141] (854s left) kms_ccs (pipe-a-bad-rotation-90-4_tiled_dg2_rc_ccs_cc)
[182.719801] Starting subtest: pipe-A-bad-rotation-90-4_tiled_dg2_rc_ccs_cc
[182.725102] Subtest pipe-A-bad-rotation-90-4_tiled_dg2_rc_ccs_cc: SKIP (0.000s)
[183.358975] [043/141] (853s left) gem_wait (invalid-flags)
[183.577870] Starting subtest: invalid-flags
[183.580183] Subtest invalid-flags: SUCCESS (0.000s)
[183.860429] [044/141] (853s left) kms_vblank (pipe-c-ts-continuation-idle-hang)
[184.166637] Starting subtest: pipe-C-ts-continuation-idle-hang
[192.075870] Subtest pipe-C-ts-continuation-idle-hang: SUCCESS (7.909s)
[192.399024] [045/141] (844s left) v3d/v3d_wait_bo (bad-pad)
[192.657731] Subtest bad-pad: SKIP (0.000s)
[192.734855] [046/141] (844s left) kms_flip (nonblocking-read)
[193.017692] Starting subtest: nonblocking-read
[193.019780] Subtest nonblocking-read: SUCCESS (0.000s)
[193.134097] [047/141] (844s left) gem_exec_reloc (basic-wc-read-noreloc)
[193.352287] Starting subtest: basic-wc-read-noreloc
[193.362302] Subtest basic-wc-read-noreloc: SUCCESS (0.010s)
[193.548979] [048/141] (843s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[193.867124] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-cpu
[193.871241] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[193.992865] [049/141] (843s left) gem_exec_fence (basic-await)
[194.356026] Starting subtest: basic-await
[194.358409] Starting dynamic subtest: rcs0
[194.487178] Dynamic subtest rcs0: SUCCESS (0.130s)
[194.511275] Starting dynamic subtest: bcs0
[194.612282] Dynamic subtest bcs0: SUCCESS (0.124s)
[194.638557] Starting dynamic subtest: vcs0
[194.742635] Dynamic subtest vcs0: SUCCESS (0.128s)
[194.764122] Starting dynamic subtest: vecs0
[194.871131] Dynamic subtest vecs0: SUCCESS (0.128s)
[194.873461] Subtest basic-await: SUCCESS (0.515s)
[195.615845] [050/141] (841s left) kms_pipe_crc_basic (bad-source)
[195.928599] Starting subtest: bad-source
[195.930949] Subtest bad-source: SUCCESS (0.001s)
[196.018227] [051/141] (841s left) kms_big_fb (4-tiled-32bpp-rotate-270)
[196.354983] Starting subtest: 4-tiled-32bpp-rotate-270
[196.359404] Subtest 4-tiled-32bpp-rotate-270: SKIP (0.000s)
[196.468473] [052/141] (840s left) gem_softpin (evict-prime-sanity-check)
[196.706303] Starting subtest: evict-prime-sanity-check
[196.708794] Starting dynamic subtest: rcs0
[197.837300] Dynamic subtest rcs0: SUCCESS (1.131s)
[197.860812] Starting dynamic subtest: bcs0
[198.947315] Dynamic subtest bcs0: SUCCESS (1.109s)
[198.976199] Starting dynamic subtest: vcs0
[200.062446] Dynamic subtest vcs0: SUCCESS (1.114s)
[200.086931] Starting dynamic subtest: vecs0
[201.167353] Dynamic subtest vecs0: SUCCESS (1.104s)
[201.190224] Starting dynamic subtest: all
[202.273761] Dynamic subtest all: SUCCESS (1.106s)
[202.276079] Subtest evict-prime-sanity-check: SUCCESS (5.567s)
[202.365762] [053/141] (835s left) gem_basic (bad-close)
[202.577471] Starting subtest: bad-close
[202.581648] Subtest bad-close: SUCCESS (0.000s)
[202.668493] [054/141] (834s left) gem_exec_fence (syncobj-repeat)
[203.342027] Starting subtest: syncobj-repeat
[204.203398] Subtest syncobj-repeat: SUCCESS (0.861s)
[204.570007] [055/141] (833s left) prime_mmap (test_dup)
[204.781531] Starting subtest: test_dup
[204.783604] Starting dynamic subtest: test_dup-smem
[204.789173] Dynamic subtest test_dup-smem: SUCCESS (0.008s)
[204.791922] Subtest test_dup: SUCCESS (0.009s)
[204.882529] [056/141] (832s left) sw_sync (sync_multi_timeline_wait)
[205.089756] Starting subtest: sync_multi_timeline_wait
[205.092095] Subtest sync_multi_timeline_wait: SUCCESS (0.001s)
[205.165941] [057/141] (832s left) i915_pm_rps (engine-order)
[205.384138] Starting subtest: engine-order
[220.124049] Subtest engine-order: FAIL (14.700s)
[220.820402] [058/141] (816s left) gem_exec_reloc (basic-gtt-cpu-active)
[221.035449] Starting subtest: basic-gtt-cpu-active
[221.181163] Subtest basic-gtt-cpu-active: SUCCESS (0.145s)
[221.366633] [059/141] (816s left) kms_ccs (pipe-d-crc-sprite-planes-basic-4_tiled_mtl_rc_ccs)
[222.074436] Subtest pipe-D-crc-sprite-planes-basic-4_tiled_mtl_rc_ccs: SKIP (0.000s)
[222.372781] [060/141] (815s left) syncobj_timeline (invalid-multi-wait-available-unsubmitted-submitted-signaled
[222.584042] Starting subtest: invalid-multi-wait-available-unsubmitted-submitted-signaled
[222.586270] Subtest invalid-multi-wait-available-unsubmitted-submitted-signaled: SUCCESS (0.002s)
[222.672659] [061/141] (815s left) kms_cursor_crc (cursor-random-max-size)
[222.992572] Starting subtest: cursor-random-max-size
[222.998486] Subtest cursor-random-max-size: SKIP (0.000s)
[223.089837] [062/141] (814s left) syncobj_timeline (host-signal-ordered)
[223.296304] Starting subtest: host-signal-ordered
[223.298772] Subtest host-signal-ordered: SUCCESS (0.002s)
[223.389448] [063/141] (814s left) kms_cursor_legacy (cursora-vs-flipa-toggle)
[223.700586] Starting subtest: cursorA-vs-flipA-toggle
[234.753063] Subtest cursorA-vs-flipA-toggle: SUCCESS (11.047s)
[235.089926] [064/141] (802s left) kms_ccs (pipe-c-missing-ccs-buffer-4_tiled_mtl_rc_ccs)
[235.785487] Starting subtest: pipe-C-missing-ccs-buffer-4_tiled_mtl_rc_ccs
[235.790443] Subtest pipe-C-missing-ccs-buffer-4_tiled_mtl_rc_ccs: SKIP (0.000s)
[236.147868] [065/141] (801s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[236.458699] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[236.464426] Starting dynamic subtest: A-DP1
[237.699274] Dynamic subtest A-DP1: SUCCESS (1.233s)
[237.723857] Starting dynamic subtest: B-DP1
[238.766124] Dynamic subtest B-DP1: SUCCESS (1.062s)
[238.789392] Starting dynamic subtest: C-DP1
[239.797896] Dynamic subtest C-DP1: SUCCESS (1.031s)
[239.800305] Subtest single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SUCCESS (3.335s)
[240.134917] [066/141] (797s left) kms_pipe_b_c_ivb (pipe-b-double-modeset-then-modeset-pipe-c)
[240.346365] Subtest pipe-B-double-modeset-then-modeset-pipe-C: SKIP (0.000s)
[240.397412] [067/141] (797s left) gem_ccs (block-copy-compressed)
[240.621834] Subtest block-copy-compressed: SKIP (0.000s)
[240.680984] [068/141] (797s left) drm_read (short-buffer-wakeup)
[241.124541] Starting subtest: short-buffer-wakeup
[273.337457] Subtest short-buffer-wakeup: SUCCESS (32.215s)
[273.482271] [069/141] (764s left) kms_chamelium_frames (hdmi-cmp-planar-formats)
[273.875051] Subtest hdmi-cmp-planar-formats: SKIP (0.000s)
[274.173694] [070/141] (764s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[274.496458] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[274.504778] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.003s)
[274.600986] [071/141] (763s left) gem_readwrite (new-obj)
[274.822583] Starting subtest: new-obj
[274.826626] Subtest new-obj: SUCCESS (0.001s)
[274.934404] [072/141] (763s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[275.245966] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc
[275.250067] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[275.353113] [073/141] (763s left) core_auth (getclient-master-drop)
[275.559205] Starting subtest: getclient-master-drop
[275.564199] Subtest getclient-master-drop: SUCCESS (0.004s)
[275.651623] [074/141] (762s left) gen7_exec_parse (basic-offset)
[275.874152] Subtest basic-offset: SKIP (0.000s)
[275.933200] [075/141] (762s left) kms_writeback (writeback-pixel-formats)
[276.335458] Subtest writeback-pixel-formats: SKIP (0.000s)
[276.399301] [076/141] (762s left) gem_exec_schedule (u-submit-early-slice)
[276.637205] Starting subtest: u-submit-early-slice
[276.639693] Starting dynamic subtest: rcs0
[276.710943] Dynamic subtest rcs0: SUCCESS (0.072s)
[276.735290] Starting dynamic subtest: bcs0
[276.775488] Dynamic subtest bcs0: SUCCESS (0.064s)
[276.801109] Starting dynamic subtest: vcs0
[276.832167] Dynamic subtest vcs0: SUCCESS (0.056s)
[276.857959] Starting dynamic subtest: vecs0
[276.888433] Dynamic subtest vecs0: SUCCESS (0.055s)
[276.890686] Subtest u-submit-early-slice: SUCCESS (0.251s)
[277.251183] [077/141] (761s left) i915_pm_rpm (system-suspend-execbuf)
[279.069843] Starting subtest: system-suspend-execbuf
[299.984716] Subtest system-suspend-execbuf: SUCCESS (4.701s)
[302.389283] [078/141] (754s left) kms_plane_scaling (planes-downscale-factor-0-5-unity-scaling)
[302.700024] Starting subtest: planes-downscale-factor-0-5-unity-scaling
[302.702127] Starting dynamic subtest: pipe-A-DP-1
[303.040089] Dynamic subtest pipe-A-DP-1: SUCCESS (0.338s)
[303.063521] Starting dynamic subtest: pipe-B-DP-1
[303.428084] Dynamic subtest pipe-B-DP-1: SUCCESS (0.378s)
[303.450577] Starting dynamic subtest: pipe-C-DP-1
[303.808069] Dynamic subtest pipe-C-DP-1: SUCCESS (0.381s)
[303.810256] Subtest planes-downscale-factor-0-5-unity-scaling: SUCCESS (1.111s)
[304.214324] [079/141] (752s left) kms_plane_lowres (tiling-x)
[304.529505] Starting subtest: tiling-x
[304.531877] Starting dynamic subtest: pipe-A-DP-1
[310.389116] Dynamic subtest pipe-A-DP-1: SUCCESS (5.862s)
[310.529121] Starting dynamic subtest: pipe-B-DP-1
[316.366496] Dynamic subtest pipe-B-DP-1: SUCCESS (5.857s)
[316.471884] Starting dynamic subtest: pipe-C-DP-1
[320.358398] Dynamic subtest pipe-C-DP-1: SUCCESS (3.905s)
[320.474474] Subtest tiling-x: SUCCESS (15.948s)
[320.927375] [080/141] (735s left) gem_exec_suspend (basic-s3-devices)
[321.166060] Starting subtest: basic-S3-devices
[321.168507] Starting dynamic subtest: smem
[330.972520] Dynamic subtest smem: SUCCESS (9.806s)
[330.974644] Subtest basic-S3-devices: SUCCESS (9.807s)
[337.193625] [081/141] (725s left) kms_getfb (getfb2-into-addfb2)
[337.406367] Starting subtest: getfb2-into-addfb2
[337.408798] Subtest getfb2-into-addfb2: SUCCESS (0.000s)
[337.519549] [082/141] (725s left) gem_mmap_offset (close-race)
[337.727130] Starting subtest: close-race
[357.900803] Subtest close-race: SUCCESS (20.173s)
[358.019170] [083/141] (704s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-pixel-formats)
[358.337516] Starting subtest: plane-scaler-with-clipping-clamping-pixel-formats
[358.339847] Starting dynamic subtest: pipe-A-DP-1
[360.741026] Dynamic subtest pipe-A-DP-1: SUCCESS (2.401s)
[360.765803] Starting dynamic subtest: pipe-B-DP-1
[363.360260] Dynamic subtest pipe-B-DP-1: SUCCESS (2.614s)
[363.390802] Starting dynamic subtest: pipe-C-DP-1
[363.764638] Dynamic subtest pipe-C-DP-1: SUCCESS (0.400s)
[363.766897] Subtest plane-scaler-with-clipping-clamping-pixel-formats: SUCCESS (5.431s)
[364.168873] [084/141] (698s left) gem_pxp (hw-rejects-pxp-buffer)
[366.901541] Starting subtest: hw-rejects-pxp-buffer
[366.903913] Subtest hw-rejects-pxp-buffer: SUCCESS (0.000s)
[366.997701] [085/141] (695s left) kms_flip (plain-flip-fb-recreate-interruptible)
[367.297112] Starting subtest: plain-flip-fb-recreate-interruptible
[367.306104] Starting dynamic subtest: A-DP1
[378.113454] Dynamic subtest A-DP1: SUCCESS (10.811s)
[378.139371] Starting dynamic subtest: B-DP1
[388.839135] Dynamic subtest B-DP1: SUCCESS (10.724s)
[388.864833] Starting dynamic subtest: C-DP1
[399.569050] Dynamic subtest C-DP1: SUCCESS (10.727s)
[399.571130] Subtest plain-flip-fb-recreate-interruptible: SUCCESS (32.274s)
[399.968036] [086/141] (663s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[400.300932] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[400.305068] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[400.400683] [087/141] (662s left) gem_exec_balancer (full)
[400.642751] Starting subtest: full
[404.627863] Subtest full: SUCCESS (3.988s)
[408.565850] [088/141] (654s left) kms_prime (basic-modeset-hybrid)
[410.589043] Subtest basic-modeset-hybrid: SKIP (0.000s)
[410.731658] [089/141] (652s left) syncobj_wait (multi-wait-for-submit-unsubmitted)
[410.943785] Starting subtest: multi-wait-for-submit-unsubmitted
[411.044421] Subtest multi-wait-for-submit-unsubmitted: SUCCESS (0.101s)
[411.129371] [090/141] (652s left) gem_spin_batch (spin-all)
[411.359954] Starting subtest: spin-all
[411.388623] Subtest spin-all: SUCCESS (0.028s)
[411.631805] [091/141] (651s left) kms_ccs (pipe-b-crc-primary-rotation-180-4_tiled_dg2_rc_ccs_cc)
[412.169207] Starting subtest: pipe-B-crc-primary-rotation-180-4_tiled_dg2_rc_ccs_cc
[412.174262] Subtest pipe-B-crc-primary-rotation-180-4_tiled_dg2_rc_ccs_cc: SKIP (0.002s)
[412.689122] [092/141] (650s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[413.018477] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[413.026062] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.003s)
[413.125972] [093/141] (650s left) vc4/vc4_perfmon (create-perfmon-0)
[413.394971] Subtest create-perfmon-0: SKIP (0.000s)
[413.471838] [094/141] (649s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-render)
[413.797674] Starting subtest: psr-1p-primscrn-cur-indfb-draw-render
[413.801891] Subtest psr-1p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[413.905893] [095/141] (649s left) gem_exec_reloc (basic-cpu)
[414.128452] Starting subtest: basic-cpu
[414.154184] Subtest basic-cpu: SUCCESS (0.024s)
[414.357158] [096/141] (649s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-gtt)
[414.690924] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-gtt
[416.792669] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-gtt: SUCCESS (2.107s)
[416.938639] [097/141] (646s left) kms_color (ctm-red-to-blue)
[417.242703] Starting subtest: ctm-red-to-blue
[417.244971] Starting dynamic subtest: pipe-A
[417.757049] Dynamic subtest pipe-A: SUCCESS (0.517s)
[417.787445] Starting dynamic subtest: pipe-B
[418.556433] Dynamic subtest pipe-B: SUCCESS (0.791s)
[418.605257] Starting dynamic subtest: pipe-C
[419.474548] Dynamic subtest pipe-C: SUCCESS (0.923s)
[419.476699] Subtest ctm-red-to-blue: SUCCESS (2.236s)
[419.800291] [098/141] (643s left) kms_ccs (pipe-b-ccs-on-another-bo-4_tiled_mtl_rc_ccs)
[420.406142] Starting subtest: pipe-B-ccs-on-another-bo-4_tiled_mtl_rc_ccs
[420.412656] Subtest pipe-B-ccs-on-another-bo-4_tiled_mtl_rc_ccs: SKIP (0.000s)
[420.884874] [099/141] (642s left) i915_hangman (hangcheck-unterminated)
[421.148593] Starting subtest: hangcheck-unterminated
[430.539873] Subtest hangcheck-unterminated: SUCCESS (9.422s)
[430.669101] [100/141] (632s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[430.983265] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[430.987466] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[431.085332] [101/141] (632s left) kms_vblank (pipe-a-wait-forked-busy)
[431.396484] Starting subtest: pipe-A-wait-forked-busy
[433.854554] Subtest pipe-A-wait-forked-busy: SUCCESS (2.460s)
[434.199330] [102/141] (629s left) kms_rotation_crc (exhaust-fences)
[434.507978] Starting subtest: exhaust-fences
[436.185749] Subtest exhaust-fences: SUCCESS (1.682s)
[436.349147] [103/141] (627s left) gem_exec_balancer (individual)
[436.579642] Starting subtest: individual
[440.401822] Subtest individual: SUCCESS (3.821s)
[444.264342] [104/141] (619s left) gem_userptr_blits (map-fixed-invalidate-overlap-busy)
[444.510798] Starting subtest: map-fixed-invalidate-overlap-busy
[444.513091] Starting dynamic subtest: gtt
[448.658778] Dynamic subtest gtt: SUCCESS (4.146s)
[448.684012] Starting dynamic subtest: wb
[452.692154] Dynamic subtest wb: SUCCESS (4.032s)
[452.716803] Starting dynamic subtest: wc
[456.702018] Dynamic subtest wc: SUCCESS (4.008s)
[456.727237] Starting dynamic subtest: uc
[460.733483] Dynamic subtest uc: SUCCESS (4.030s)
[460.735690] Subtest map-fixed-invalidate-overlap-busy: SUCCESS (16.222s)
[460.881468] [105/141] (602s left) kms_cursor_crc (cursor-dpms)
[461.195643] Starting subtest: cursor-dpms
[461.201640] Starting dynamic subtest: pipe-A-DP-1
[462.620025] Dynamic subtest pipe-A-DP-1: SUCCESS (1.417s)
[462.656279] Starting dynamic subtest: pipe-C-DP-1
[464.318708] Dynamic subtest pipe-C-DP-1: SUCCESS (1.695s)
[464.321151] Subtest cursor-dpms: SUCCESS (3.124s)
[464.679609] [106/141] (599s left) kms_plane_alpha_blend (alpha-basic)
[464.989898] Starting subtest: alpha-basic
[465.296213] Starting dynamic subtest: pipe-A-DP-1
[466.072230] Dynamic subtest pipe-A-DP-1: FAIL (0.174s)
[466.097440] Starting dynamic subtest: pipe-C-DP-1
[466.781958] Dynamic subtest pipe-C-DP-1: FAIL (0.670s)
[466.784308] Subtest alpha-basic: FAIL (1.237s)
[467.009110] [107/141] (596s left) kms_draw_crc (draw-method-mmap-cpu)
[467.318290] Starting subtest: draw-method-mmap-cpu
[467.320568] Starting dynamic subtest: xrgb8888-untiled
[467.611295] Dynamic subtest xrgb8888-untiled: SUCCESS (0.294s)
[467.636337] Starting dynamic subtest: rgb565-untiled
[467.877183] Dynamic subtest rgb565-untiled: SUCCESS (0.265s)
[467.903411] Starting dynamic subtest: xrgb2101010-untiled
[468.160135] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.282s)
[468.184082] Starting dynamic subtest: xrgb8888-xtiled
[468.343474] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.182s)
[468.367433] Starting dynamic subtest: rgb565-xtiled
[468.527394] Dynamic subtest rgb565-xtiled: SUCCESS (0.182s)
[468.553137] Starting dynamic subtest: xrgb2101010-xtiled
[468.727402] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.199s)
[468.753535] Starting dynamic subtest: xrgb8888-ytiled
[468.926767] Dynamic subtest xrgb8888-ytiled: SUCCESS (0.198s)
[468.952727] Starting dynamic subtest: rgb565-ytiled
[469.112171] Dynamic subtest rgb565-ytiled: SUCCESS (0.182s)
[469.136062] Starting dynamic subtest: xrgb2101010-ytiled
[469.293289] Dynamic subtest xrgb2101010-ytiled: SUCCESS (0.182s)
[469.295509] Subtest draw-method-mmap-cpu: SUCCESS (1.979s)
[469.408735] [108/141] (594s left) gem_userptr_blits (unsync-unmap)
[469.627893] Subtest unsync-unmap: SKIP (0.000s)
[469.792330] [109/141] (594s left) kms_psr (psr2_cursor_mmap_gtt)
[470.001604] Subtest psr2_cursor_mmap_gtt: SKIP (0.000s)
[470.057981] [110/141] (594s left) syncobj_wait (invalid-reset-one-illegal-handle)
[470.263739] Starting subtest: invalid-reset-one-illegal-handle
[470.266104] Subtest invalid-reset-one-illegal-handle: SUCCESS (0.001s)
[470.358798] [111/141] (593s left) kms_ccs (pipe-d-crc-primary-basic-4_tiled_mtl_rc_ccs)
[471.125678] Subtest pipe-D-crc-primary-basic-4_tiled_mtl_rc_ccs: SKIP (0.000s)
[471.439366] [112/141] (592s left) gem_eio (wait-wedge-10ms)
[471.676488] Starting subtest: wait-wedge-10ms
[473.508040] Subtest wait-wedge-10ms: SUCCESS (1.832s)
[473.688523] [113/141] (590s left) kms_invalid_mode (bad-vsync-start)
[474.003593] Starting subtest: bad-vsync-start
[474.006326] Starting dynamic subtest: DP-1-pipe-A
[474.098977] Dynamic subtest DP-1-pipe-A: SUCCESS (0.099s)
[474.128115] Starting dynamic subtest: DP-1-pipe-B
[474.130829] Dynamic subtest DP-1-pipe-B: SUCCESS (0.014s)
[474.158610] Starting dynamic subtest: DP-1-pipe-C
[474.161245] Dynamic subtest DP-1-pipe-C: SUCCESS (0.014s)
[474.163560] Subtest bad-vsync-start: SUCCESS (0.140s)
[474.467604] [114/141] (589s left) gem_lmem_swapping (parallel-random-verify-ccs)
[474.690744] Subtest parallel-random-verify-ccs: SKIP (0.000s)
[474.750095] [115/141] (589s left) syncobj_timeline (multi-wait-available-submitted-signaled)
[474.959528] Starting subtest: multi-wait-available-submitted-signaled
[474.961887] Subtest multi-wait-available-submitted-signaled: SUCCESS (0.002s)
[475.051401] [116/141] (589s left) feature_discovery (display)
[475.361410] Starting subtest: display
[475.363658] Subtest display: SUCCESS (0.000s)
[475.451454] [117/141] (588s left) prime_mmap (test_correct)
[475.669258] Starting subtest: test_correct
[475.671641] Starting dynamic subtest: test_correct-smem
[475.677401] Dynamic subtest test_correct-smem: SUCCESS (0.008s)
[475.679820] Subtest test_correct: SUCCESS (0.009s)
[475.783779] [118/141] (588s left) kms_ccs (pipe-a-crc-sprite-planes-basic-yf_tiled_ccs)
[476.291376] Starting subtest: pipe-A-crc-sprite-planes-basic-yf_tiled_ccs
[476.814256] Subtest pipe-A-crc-sprite-planes-basic-yf_tiled_ccs: SUCCESS (0.523s)
[477.410913] [119/141] (587s left) gem_ctx_persistence (file)
[477.631526] Starting subtest: file
[477.707446] Subtest file: SUCCESS (0.076s)
[477.810499] [120/141] (586s left) gem_ctx_persistence (saturated-hostile)
[478.022933] Starting subtest: saturated-hostile
[478.025491] Starting dynamic subtest: rcs0
[478.135523] Dynamic subtest rcs0: SUCCESS (0.111s)
[478.160956] Starting dynamic subtest: bcs0
[478.255574] Dynamic subtest bcs0: SUCCESS (0.119s)
[478.281741] Starting dynamic subtest: vcs0
[478.368562] Dynamic subtest vcs0: SUCCESS (0.112s)
[478.393664] Starting dynamic subtest: vecs0
[478.480120] Dynamic subtest vecs0: SUCCESS (0.111s)
[478.482214] Subtest saturated-hostile: SUCCESS (0.457s)
[478.612587] [121/141] (585s left) sysfs_timeslice_duration (idempotent)
[478.831473] Starting subtest: idempotent
[478.833762] Starting dynamic subtest: vecs0
[478.836028] Dynamic subtest vecs0: SUCCESS (0.001s)
[478.860288] Starting dynamic subtest: vcs0
[478.862461] Dynamic subtest vcs0: SUCCESS (0.001s)
[478.886137] Starting dynamic subtest: bcs0
[478.888247] Dynamic subtest bcs0: SUCCESS (0.001s)
[478.912287] Starting dynamic subtest: rcs0
[478.914425] Dynamic subtest rcs0: SUCCESS (0.001s)
[478.916491] Subtest idempotent: SUCCESS (0.013s)
[478.953095] [122/141] (585s left) kms_chamelium_edid (hdmi-mode-timings)
[479.351694] Subtest hdmi-mode-timings: SKIP (0.000s)
[479.645842] [123/141] (585s left) kms_ccs (pipe-c-bad-rotation-90-y_tiled_gen12_mc_ccs)
[480.270824] Starting subtest: pipe-C-bad-rotation-90-y_tiled_gen12_mc_ccs
[480.275178] Subtest pipe-C-bad-rotation-90-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[480.677394] [124/141] (584s left) gem_eio (in-flight-contexts-immediate)
[480.906439] Starting subtest: in-flight-contexts-immediate
[492.535275] Subtest in-flight-contexts-immediate: SUCCESS (11.629s)
[492.743651] [125/141] (572s left) i915_pm_rpm (dpms-lpsp)
[494.560714] Starting subtest: dpms-lpsp
[494.567488] Subtest dpms-lpsp: SKIP (0.000s)
[495.028327] [126/141] (569s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-wc)
[495.352731] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-wc
[495.356773] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[495.459371] [127/141] (569s left) gem_mmap (bad-offset)
[495.663653] Starting subtest: bad-offset
[495.666020] Subtest bad-offset: SUCCESS (0.001s)
[495.775361] [128/141] (569s left) kms_tv_load_detect (load-detect)
[495.987087] Subtest load-detect: SKIP (0.000s)
[496.041030] [129/141] (568s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions)
[496.341415] Starting subtest: short-busy-flip-before-cursor-atomic-transitions
[496.345572] Subtest short-busy-flip-before-cursor-atomic-transitions: SKIP (0.000s)
[496.425525] [130/141] (568s left) perf (polling-small-buf)
[496.769041] Starting subtest: polling-small-buf
[496.901741] Subtest polling-small-buf: SUCCESS (0.136s)
[496.955351] [131/141] (568s left) kms_cursor_legacy (nonblocking-modeset-vs-cursor-atomic)
[497.260587] Starting subtest: nonblocking-modeset-vs-cursor-atomic
[497.834466] Subtest nonblocking-modeset-vs-cursor-atomic: SUCCESS (0.578s)
[498.172732] [132/141] (566s left) kms_big_fb (yf-tiled-8bpp-rotate-270)
[498.504279] Starting subtest: yf-tiled-8bpp-rotate-270
[498.508724] Subtest yf-tiled-8bpp-rotate-270: SKIP (0.000s)
[498.621342] [133/141] (566s left) syncobj_timeline (wait-all-for-submit-delayed-submit)
[498.829335] Starting subtest: wait-all-for-submit-delayed-submit
[498.933589] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.104s)
[499.021112] [134/141] (566s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-mmap-wc)
[499.335782] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-mmap-wc
[499.339955] Subtest fbcpsr-1p-offscren-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[499.438063] [135/141] (565s left) kms_flip (flip-vs-wf_vblank-interruptible)
[499.741192] Starting subtest: flip-vs-wf_vblank-interruptible
[499.747047] Starting dynamic subtest: A-DP1
[501.218523] Dynamic subtest A-DP1: SUCCESS (1.472s)
[501.246364] Starting dynamic subtest: B-DP1
[502.594412] Dynamic subtest B-DP1: SUCCESS (1.374s)
[502.622249] Starting dynamic subtest: C-DP1
[503.978162] Dynamic subtest C-DP1: SUCCESS (1.381s)
[503.980385] Subtest flip-vs-wf_vblank-interruptible: SUCCESS (4.238s)
[504.374991] [136/141] (560s left) gem_sync (basic-each)
[504.625911] Starting subtest: basic-each
[507.020188] Subtest basic-each: SUCCESS (2.412s)
[507.240019] [137/141] (558s left) kms_big_fb (y-tiled-addfb-size-offset-overflow)
[507.569741] Starting subtest: y-tiled-addfb-size-offset-overflow
[507.576622] Subtest y-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[507.673312] [138/141] (557s left) gen9_exec_parse (unaligned-access)
[507.908451] Starting subtest: unaligned-access
[507.979801] Subtest unaligned-access: SUCCESS (0.073s)
[508.205387] [139/141] (557s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[508.527603] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu
[508.531865] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[508.623603] [140/141] (556s left) gem_render_copy (yf-tiled-mc-ccs-to-vebox-y-tiled)
[508.848977] Starting subtest: yf-tiled-mc-ccs-to-vebox-y-tiled
[508.852902] Subtest yf-tiled-mc-ccs-to-vebox-y-tiled: SKIP (0.000s)
[509.055143] [141/141] (556s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-32bpp-yftile-upscaling)
[509.362425] Starting subtest: flip-64bpp-yftile-to-32bpp-yftile-upscaling
[509.366208] Starting dynamic subtest: pipe-A-valid-mode
[509.461169] Dynamic subtest pipe-A-valid-mode: SKIP (0.096s)
[509.465759] Subtest flip-64bpp-yftile-to-32bpp-yftile-upscaling: SKIP (0.099s)
[509.766108] Closing watchdogs
Done.