[74.014632] Initializing watchdogs
[74.015210]   /dev/watchdog0
[74.048728] [001/142] (960s left) kms_plane_scaling (planes-downscale-factor-0-5-upscale-20x20)
[74.571564] Starting subtest: planes-downscale-factor-0-5-upscale-20x20
[74.574997] Starting dynamic subtest: pipe-A-eDP-1
[74.628389] Dynamic subtest pipe-A-eDP-1: SKIP (0.048s)
[74.651291] Starting dynamic subtest: pipe-B-eDP-1
[74.779221] Dynamic subtest pipe-B-eDP-1: SKIP (0.142s)
[74.805355] Starting dynamic subtest: pipe-C-eDP-1
[74.932777] Dynamic subtest pipe-C-eDP-1: SKIP (0.146s)
[74.960704] Starting dynamic subtest: pipe-D-eDP-1
[75.085554] Dynamic subtest pipe-D-eDP-1: SKIP (0.145s)
[75.092647] Subtest planes-downscale-factor-0-5-upscale-20x20: SKIP (0.512s)
[75.256810] [002/142] (959s left) fbdev (unaligned-read)
[75.649943] Starting subtest: unaligned-read
[75.703785] Subtest unaligned-read: SUCCESS (0.057s)
[75.844261] [003/142] (958s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions-varying-size)
[76.387970] Starting subtest: short-flip-after-cursor-atomic-transitions-varying-size
[77.893754] Subtest short-flip-after-cursor-atomic-transitions-varying-size: SUCCESS (1.507s)
[78.873457] [004/142] (955s left) kms_ccs (pipe-b-bad-aux-stride-y_tiled_gen12_rc_ccs_cc)
[80.163354] Starting subtest: pipe-B-bad-aux-stride-y_tiled_gen12_rc_ccs_cc
[80.169490] Subtest pipe-B-bad-aux-stride-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[81.229112] [005/142] (953s left) v3d/v3d_mmap (mmap-bad-handle)
[81.713058] Subtest mmap-bad-handle: SKIP (0.000s)
[81.813605] [006/142] (953s left) kms_dsc (dsc-with-bpc-formats)
[82.299070] Starting subtest: dsc-with-bpc-formats
[82.325133] Subtest dsc-with-bpc-formats: SKIP (0.023s)
[82.451565] [007/142] (952s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[82.926688] Starting subtest: flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[82.933070] Starting dynamic subtest: pipe-A-default-mode
[83.493624] Dynamic subtest pipe-A-default-mode: SKIP (0.556s)
[83.500771] Subtest flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.566s)
[84.271836] [008/142] (950s left) kms_fbcon_fbt (fbc-suspend)
[84.668118] Starting subtest: fbc-suspend
[134.884366] Subtest fbc-suspend: SUCCESS (50.214s)
[135.068084] [009/142] (900s left) gem_ctx_persistence (legacy-engines-mixed-process)
[135.473722] Starting subtest: legacy-engines-mixed-process
[135.477516] Starting dynamic subtest: render
[137.552924] Dynamic subtest render: SUCCESS (2.074s)
[137.597379] Starting dynamic subtest: bsd1
[157.653773] Dynamic subtest bsd1: SUCCESS (20.102s)
[157.692989] Starting dynamic subtest: bsd2
[177.758170] Dynamic subtest bsd2: SUCCESS (20.102s)
[177.797422] Starting dynamic subtest: blt
[179.554676] Dynamic subtest blt: SUCCESS (1.789s)
[179.604723] Starting dynamic subtest: vebox
[199.673510] Dynamic subtest vebox: SUCCESS (20.122s)
[199.677582] Subtest legacy-engines-mixed-process: SUCCESS (64.200s)
[199.852543] [010/142] (835s left) kms_cursor_legacy (cursora-vs-flipb-atomic)
[200.436517] Starting subtest: cursorA-vs-flipB-atomic
[200.447358] Subtest cursorA-vs-flipB-atomic: SKIP (0.008s)
[200.746880] [011/142] (834s left) kms_rotation_crc (exhaust-fences)
[201.219141] Starting subtest: exhaust-fences
[201.226952] Subtest exhaust-fences: SKIP (0.000s)
[201.357165] [012/142] (833s left) i915_pm_rpm (basic-pci-d3-state)
[203.682668] Starting subtest: basic-pci-d3-state
[208.453161] Subtest basic-pci-d3-state: SUCCESS (4.770s)
[208.651149] [013/142] (826s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-pwrite)
[209.151838] Starting subtest: fbc-1p-offscren-pri-indfb-draw-pwrite
[220.790360] Subtest fbc-1p-offscren-pri-indfb-draw-pwrite: SUCCESS (11.643s)
[221.070925] [014/142] (814s left) gem_softpin (invalid)
[221.466898] Starting subtest: invalid
[221.505581] Subtest invalid: SUCCESS (0.041s)
[221.687608] [015/142] (813s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[222.223395] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[222.230873] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[222.390953] [016/142] (813s left) gem_exec_reloc (basic-gtt-read-active)
[222.788335] Subtest basic-gtt-read-active: SKIP (0.000s)
[222.918269] [017/142] (812s left) kms_ccs (pipe-a-crc-primary-rotation-180-yf_tiled_ccs)
[223.871944] Starting subtest: pipe-A-crc-primary-rotation-180-yf_tiled_ccs
[223.878185] Subtest pipe-A-crc-primary-rotation-180-yf_tiled_ccs: SKIP (0.002s)
[225.290160] [018/142] (810s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-submi
[225.677357] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-submitted
[225.783989] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-submitted: SUCCESS (0.106s)
[225.924198] [019/142] (809s left) gem_exec_schedule (preempt-queue)
[226.373961] Subtest preempt-queue: SKIP (0.000s)
[226.795161] [020/142] (809s left) syncobj_timeline (invalid-multi-wait-available-unsubmitted-submitted-signaled
[227.178607] Starting subtest: invalid-multi-wait-available-unsubmitted-submitted-signaled
[227.182371] Subtest invalid-multi-wait-available-unsubmitted-submitted-signaled: SUCCESS (0.003s)
[227.324562] [021/142] (808s left) v3d/v3d_wait_bo (bad-bo)
[227.788103] Subtest bad-bo: SKIP (0.000s)
[227.914324] [022/142] (808s left) gem_exec_balancer (waits)
[228.334531] Starting subtest: waits
[250.343398] Subtest waits: SUCCESS (22.008s)
[251.359749] [023/142] (784s left) kms_ccs (pipe-b-crc-primary-basic-y_tiled_gen12_rc_ccs_cc)
[252.550608] Starting subtest: pipe-B-crc-primary-basic-y_tiled_gen12_rc_ccs_cc
[252.557129] Subtest pipe-B-crc-primary-basic-y_tiled_gen12_rc_ccs_cc: SKIP (0.002s)
[253.794598] [024/142] (782s left) debugfs_test (read_all_entries_display_on)
[254.294618] Starting subtest: read_all_entries_display_on
[256.152829] Subtest read_all_entries_display_on: SUCCESS (1.860s)
[256.388445] [025/142] (779s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-fullscreen)
[256.906795] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-fullscreen
[269.291241] Subtest fbcpsr-1p-primscrn-spr-indfb-fullscreen: SUCCESS (12.384s)
[269.588380] [026/142] (766s left) syncobj_timeline (wait-for-submit-delayed-submit)
[269.978772] Starting subtest: wait-for-submit-delayed-submit
[270.087350] Subtest wait-for-submit-delayed-submit: SUCCESS (0.107s)
[270.247419] [027/142] (766s left) api_intel_allocator (reloc-allocator)
[270.474858] Starting subtest: reloc-allocator
[270.478503] Starting dynamic subtest: basic
[270.481976] Dynamic subtest basic: SUCCESS (0.010s)
[270.521559] Starting dynamic subtest: parallel-one
[270.525472] Dynamic subtest parallel-one: SUCCESS (0.038s)
[270.565538] Starting dynamic subtest: print
[270.569295] Dynamic subtest print: SUCCESS (0.000s)
[270.618856] Starting dynamic subtest: reuse
[270.622468] Dynamic subtest reuse: SUCCESS (0.000s)
[270.667236] Starting dynamic subtest: fork-reopen-allocator
[270.671213] Dynamic subtest fork-reopen-allocator: SUCCESS (0.076s)
[270.674769] Subtest reloc-allocator: SUCCESS (0.133s)
[270.755412] [028/142] (765s left) i915_pm_rpm (universal-planes)
[276.816847] Starting subtest: universal-planes
[276.820796] Starting dynamic subtest: plane-31
[301.491308] Dynamic subtest plane-31: SUCCESS (24.673s)
[301.534212] Starting dynamic subtest: plane-40
[330.169361] Dynamic subtest plane-40: SUCCESS (28.674s)
[330.213613] Starting dynamic subtest: plane-49
[355.035179] Dynamic subtest plane-49: SUCCESS (24.863s)
[355.084073] Starting dynamic subtest: plane-58
[379.898354] Dynamic subtest plane-58: SUCCESS (24.860s)
[379.946471] Starting dynamic subtest: plane-67
[404.770382] Dynamic subtest plane-67: SUCCESS (24.867s)
[404.818203] Starting dynamic subtest: plane-76
[429.714096] Dynamic subtest plane-76: SUCCESS (24.939s)
[429.717900] Subtest universal-planes: SUCCESS (152.900s)
[430.665149] [029/142] (606s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[431.190486] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[431.197323] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[431.339594] [030/142] (605s left) gem_exec_reloc (basic-wc-gtt-noreloc)
[431.732386] Subtest basic-wc-gtt-noreloc: SKIP (0.000s)
[431.860666] [031/142] (605s left) kms_lease (cursor_implicit_plane)
[432.305055] Starting subtest: cursor_implicit_plane
[432.313823] Starting dynamic subtest: pipe-A-eDP-1
[433.002668] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.691s)
[433.050219] Starting dynamic subtest: pipe-B-eDP-1
[434.376355] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.361s)
[434.419528] Starting dynamic subtest: pipe-C-eDP-1
[436.021625] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.641s)
[436.071995] Starting dynamic subtest: pipe-D-eDP-1
[437.934699] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.901s)
[437.938389] Subtest cursor_implicit_plane: SUCCESS (5.630s)
[438.877366] [032/142] (598s left) syncobj_wait (single-wait-signaled)
[439.273613] Starting subtest: single-wait-signaled
[439.277392] Subtest single-wait-signaled: SUCCESS (0.001s)
[439.425834] [033/142] (597s left) syncobj_wait (single-wait-all-submitted)
[439.801150] Starting subtest: single-wait-all-submitted
[439.903178] Subtest single-wait-all-submitted: SUCCESS (0.102s)
[440.047791] [034/142] (597s left) kms_cursor_edge_walk (128x128-top-bottom)
[440.538451] Starting subtest: 128x128-top-bottom
[440.543614] Starting dynamic subtest: pipe-A-eDP-1
[444.412894] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.865s)
[444.459170] Starting dynamic subtest: pipe-D-eDP-1
[447.794347] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.378s)
[447.797881] Subtest 128x128-top-bottom: SUCCESS (7.260s)
[448.737215] [035/142] (588s left) syncobj_timeline (multi-wait-available-signaled)
[449.107326] Starting subtest: multi-wait-available-signaled
[449.110989] Subtest multi-wait-available-signaled: SUCCESS (0.003s)
[449.257803] [036/142] (588s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-mmap-gtt)
[449.774191] Starting subtest: psr-1p-offscren-pri-shrfb-draw-mmap-gtt
[449.781251] Subtest psr-1p-offscren-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[449.948342] [037/142] (587s left) api_intel_allocator (reserve-simple)
[450.317372] Starting subtest: reserve-simple
[450.326675] Subtest reserve-simple: SUCCESS (0.009s)
[450.495770] [038/142] (586s left) kms_busy (extended-modeset-hang-newfb-with-reset)
[451.098991] Starting subtest: extended-modeset-hang-newfb-with-reset
[451.108410] Starting dynamic subtest: pipe-A
[459.010634] Dynamic subtest pipe-A: SUCCESS (7.897s)
[459.070275] Starting dynamic subtest: pipe-D
[466.319332] Dynamic subtest pipe-D: SUCCESS (7.287s)
[466.323073] Subtest extended-modeset-hang-newfb-with-reset: SUCCESS (15.223s)
[467.274715] [039/142] (570s left) kms_chamelium_frames (hdmi-aspect-ratio)
[468.164921] Subtest hdmi-aspect-ratio: SKIP (0.000s)
[469.009364] [040/142] (568s left) gem_set_tiling_vs_gtt
[469.523831] [041/142] (568s left) kms_flip (blocking-absolute-wf_vblank-interruptible)
[469.937418] Starting subtest: blocking-absolute-wf_vblank-interruptible
[469.970324] Starting dynamic subtest: A-eDP1
[479.288348] Dynamic subtest A-eDP1: SUCCESS (9.318s)
[479.332186] Starting dynamic subtest: B-eDP1
[488.437504] Dynamic subtest B-eDP1: SUCCESS (9.145s)
[488.480168] Starting dynamic subtest: C-eDP1
[497.550210] Dynamic subtest C-eDP1: SUCCESS (9.107s)
[497.591753] Starting dynamic subtest: D-eDP1
[506.678156] Dynamic subtest D-eDP1: SUCCESS (9.123s)
[506.706122] Subtest blocking-absolute-wf_vblank-interruptible: SUCCESS (36.769s)
[508.269897] [042/142] (529s left) gem_exec_schedule (independent)
[508.705500] Starting subtest: independent
[508.709091] Starting dynamic subtest: rcs0
[510.514972] Dynamic subtest rcs0: SUCCESS (1.811s)
[510.561456] Starting dynamic subtest: bcs0
[512.495884] Dynamic subtest bcs0: SUCCESS (1.976s)
[512.539979] Starting dynamic subtest: vcs0
[514.523147] Dynamic subtest vcs0: SUCCESS (2.022s)
[514.569476] Starting dynamic subtest: vcs1
[516.569490] Dynamic subtest vcs1: SUCCESS (2.042s)
[516.614728] Starting dynamic subtest: vecs0
[518.560549] Dynamic subtest vecs0: SUCCESS (1.987s)
[518.604841] Starting dynamic subtest: ccs0
[520.495559] Dynamic subtest ccs0: SUCCESS (1.930s)
[520.499885] Subtest independent: SUCCESS (11.797s)
[520.969959] [043/142] (516s left) gem_exec_nop (basic-series)
[521.395424] Starting subtest: basic-series
[537.516980] Subtest basic-series: SUCCESS (16.117s)
[537.851196] [044/142] (500s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-render)
[538.379957] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-render
[538.387120] Subtest psr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[538.552606] [045/142] (499s left) kms_cursor_crc (cursor-random-256x85)
[539.627372] Starting subtest: cursor-random-256x85
[539.658659] Starting dynamic subtest: pipe-A-eDP-1
[545.190147] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.527s)
[545.298870] Starting dynamic subtest: pipe-D-eDP-1
[550.673423] Dynamic subtest pipe-D-eDP-1: SUCCESS (5.417s)
[550.677478] Subtest cursor-random-256x85: SUCCESS (11.047s)
[551.640902] [046/142] (486s left) kms_rotation_crc (primary-4-tiled-reflect-x-0)
[552.126453] Starting subtest: primary-4-tiled-reflect-x-0
[567.295739] Subtest primary-4-tiled-reflect-x-0: SUCCESS (15.171s)
[568.945772] [047/142] (469s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[569.488765] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[573.369312] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (3.885s)
[573.882052] [048/142] (464s left) gem_partial_pwrite_pread (write-uncached)
[574.277419] Subtest write-uncached: SKIP (0.000s)
[574.421814] [049/142] (463s left) gem_eio (in-flight-contexts-immediate)
[576.530879] Starting subtest: in-flight-contexts-immediate
[615.936136] Subtest in-flight-contexts-immediate: SUCCESS (39.406s)
[617.569172] [050/142] (420s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[618.093618] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[618.100889] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[618.269389] [051/142] (420s left) kms_ccs (pipe-b-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs)
[619.574546] Starting subtest: pipe-B-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs
[619.585718] Subtest pipe-B-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs: SKIP (0.013s)
[620.638329] [052/142] (418s left) kms_big_fb (linear-64bpp-rotate-0)
[621.170988] Starting subtest: linear-64bpp-rotate-0
[627.902875] Subtest linear-64bpp-rotate-0: SUCCESS (6.729s)
[628.569751] [053/142] (410s left) vgem_basic (sysfs)
[629.060844] Starting subtest: sysfs
[629.064521] Subtest sysfs: SUCCESS (0.002s)
[629.196832] [054/142] (409s left) kms_psr (cursor_blt)
[629.677040] Starting subtest: cursor_blt
[632.781895] Subtest cursor_blt: SUCCESS (3.102s)
[633.727051] [055/142] (405s left) kms_prime (basic-crc-hybrid)
[637.657462] Subtest basic-crc-hybrid: SKIP (0.000s)
[637.857952] [056/142] (401s left) gem_tiled_partial_pwrite_pread (writes)
[638.255254] Subtest writes: SKIP (0.000s)
[638.380671] [057/142] (400s left) kms_panel_fitting (atomic-fastset)
[638.880136] Starting subtest: atomic-fastset
[638.883494] Starting dynamic subtest: pipe-A-eDP-1
[639.372298] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.491s)
[639.424886] Starting dynamic subtest: pipe-B-eDP-1
[641.144915] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.765s)
[641.198577] Starting dynamic subtest: pipe-C-eDP-1
[642.903142] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.750s)
[642.955899] Starting dynamic subtest: pipe-D-eDP-1
[644.630413] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.721s)
[644.634077] Subtest atomic-fastset: SUCCESS (5.756s)
[646.252872] [058/142] (392s left) gem_tiled_blits (normal)
[646.651879] Subtest normal: SKIP (0.000s)
[646.783602] [059/142] (392s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-wc)
[647.287978] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-wc
[652.899747] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (5.620s)
[653.207891] [060/142] (386s left) kms_getfb (getfb2-handle-protection)
[653.603246] Starting subtest: getfb2-handle-protection
[653.667483] Subtest getfb2-handle-protection: SUCCESS (0.068s)
[653.838722] [061/142] (385s left) gem_exec_reloc (basic-gtt-noreloc)
[654.245423] Subtest basic-gtt-noreloc: SKIP (0.000s)
[654.382667] [062/142] (385s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[654.955310] Starting subtest: cursor-vs-flip-legacy
[689.558897] Subtest cursor-vs-flip-legacy: SUCCESS (34.603s)
[690.163681] [063/142] (349s left) kms_big_fb (x-tiled-64bpp-rotate-270)
[690.707652] Starting subtest: x-tiled-64bpp-rotate-270
[690.775328] Subtest x-tiled-64bpp-rotate-270: SKIP (0.059s)
[690.957551] [064/142] (348s left) vc4/vc4_purgeable_bo (mark-unpurgeable-purged)
[691.435859] Subtest mark-unpurgeable-purged: SKIP (0.000s)
[691.569827] [065/142] (348s left) syncobj_timeline (host-signal-points)
[691.946695] Starting subtest: host-signal-points
[691.981805] Subtest host-signal-points: SUCCESS (0.035s)
[692.132597] [066/142] (347s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[692.670101] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[696.539911] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SUCCESS (3.873s)
[697.109711] [067/142] (342s left) kms_ccs (pipe-a-missing-ccs-buffer-y_tiled_gen12_mc_ccs)
[698.107362] Starting subtest: pipe-A-missing-ccs-buffer-y_tiled_gen12_mc_ccs
[698.114200] Subtest pipe-A-missing-ccs-buffer-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[699.484454] [068/142] (340s left) kms_ccs (pipe-d-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc)
[701.273748] Starting subtest: pipe-D-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc
[701.277318] Subtest pipe-D-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc: SUCCESS (0.003s)
[701.926344] [069/142] (338s left) kms_ccs (pipe-c-crc-primary-rotation-180-4_tiled_mtl_mc_ccs)
[703.352315] Starting subtest: pipe-C-crc-primary-rotation-180-4_tiled_mtl_mc_ccs
[711.293410] Subtest pipe-C-crc-primary-rotation-180-4_tiled_mtl_mc_ccs: SUCCESS (7.940s)
[712.530435] [070/142] (327s left) v3d/v3d_submit_cl (job-perfmon)
[713.012314] Subtest job-perfmon: SKIP (0.000s)
[713.144876] [071/142] (327s left) kms_pipe_crc_basic (disable-crc-after-crtc)
[713.640334] Starting subtest: disable-crc-after-crtc
[713.649201] Starting dynamic subtest: pipe-A-eDP-1
[715.889121] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.238s)
[715.941190] Starting dynamic subtest: pipe-B-eDP-1
[718.820080] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.920s)
[718.872419] Starting dynamic subtest: pipe-C-eDP-1
[722.054793] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.217s)
[722.115573] Starting dynamic subtest: pipe-D-eDP-1
[725.500970] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.442s)
[725.504561] Subtest disable-crc-after-crtc: SUCCESS (11.864s)
[726.451820] [072/142] (313s left) gem_busy (semaphore)
[727.062075] Subtest semaphore: SKIP (0.000s)
[727.386247] [073/142] (313s left) syncobj_wait (invalid-wait-illegal-handle)
[727.761870] Starting subtest: invalid-wait-illegal-handle
[727.765594] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[727.914447] [074/142] (312s left) gem_ctx_shared (exec-single-timeline)
[728.348259] Starting subtest: exec-single-timeline
[728.351943] Starting dynamic subtest: rcs0
[729.448418] Dynamic subtest rcs0: SUCCESS (1.097s)
[729.494514] Starting dynamic subtest: bcs0
[730.538121] Dynamic subtest bcs0: SUCCESS (1.085s)
[730.583058] Starting dynamic subtest: vcs0
[731.624991] Dynamic subtest vcs0: SUCCESS (1.084s)
[731.668889] Starting dynamic subtest: vcs1
[732.713502] Dynamic subtest vcs1: SUCCESS (1.084s)
[732.759199] Starting dynamic subtest: vecs0
[733.802839] Dynamic subtest vecs0: SUCCESS (1.084s)
[733.847831] Starting dynamic subtest: ccs0
[734.889455] Dynamic subtest ccs0: SUCCESS (1.084s)
[734.893254] Subtest exec-single-timeline: SUCCESS (6.542s)
[735.712353] [075/142] (304s left) gem_userptr_blits (sync-unmap)
[736.161779] Starting subtest: sync-unmap
[736.236242] Subtest sync-unmap: SUCCESS (0.074s)
[736.500127] [076/142] (304s left) kms_ccs (pipe-b-bad-rotation-90-4_tiled_mtl_mc_ccs)
[737.646325] Starting subtest: pipe-B-bad-rotation-90-4_tiled_mtl_mc_ccs
[740.731667] Subtest pipe-B-bad-rotation-90-4_tiled_mtl_mc_ccs: SUCCESS (3.081s)
[742.371972] [077/142] (298s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[742.887879] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[742.894324] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[743.060942] [078/142] (297s left) vc4/vc4_tiling (get-bad-handle)
[743.535112] Subtest get-bad-handle: SKIP (0.000s)
[743.671145] [079/142] (297s left) gem_render_copy (yf-tiled)
[744.083373] Starting subtest: yf-tiled
[744.089358] Subtest yf-tiled: SKIP (0.000s)
[744.359913] [080/142] (296s left) kms_ccs (pipe-d-missing-ccs-buffer-4_tiled_mtl_mc_ccs)
[746.067240] Starting subtest: pipe-D-missing-ccs-buffer-4_tiled_mtl_mc_ccs
[746.231880] Subtest pipe-D-missing-ccs-buffer-4_tiled_mtl_mc_ccs: SUCCESS (0.165s)
[746.897079] [081/142] (294s left) kms_ccs (pipe-c-bad-pixel-format-y_tiled_ccs)
[748.238800] Starting subtest: pipe-C-bad-pixel-format-y_tiled_ccs
[748.244459] Subtest pipe-C-bad-pixel-format-y_tiled_ccs: SKIP (0.000s)
[749.303717] [082/142] (292s left) gem_exec_create (basic)
[749.732298] Starting subtest: basic
[749.735652] Starting dynamic subtest: smem
[753.463189] Dynamic subtest smem: SUCCESS (3.728s)
[753.466660] Subtest basic: SUCCESS (3.732s)
[753.772944] [083/142] (287s left) syncobj_wait (single-wait-submitted)
[754.146189] Starting subtest: single-wait-submitted
[754.255625] Subtest single-wait-submitted: SUCCESS (0.109s)
[754.438532] [084/142] (287s left) kms_vblank (pipe-b-query-busy-hang)
[754.942225] Starting subtest: pipe-B-query-busy-hang
[770.878488] Subtest pipe-B-query-busy-hang: SUCCESS (15.939s)
[771.829348] [085/142] (269s left) gem_exec_schedule (u-fairslice)
[772.265334] Starting subtest: u-fairslice
[772.268887] Starting dynamic subtest: rcs0
[774.330457] Dynamic subtest rcs0: SUCCESS (2.059s)
[774.377161] Starting dynamic subtest: bcs0
[776.380816] Dynamic subtest bcs0: SUCCESS (2.047s)
[776.428141] Starting dynamic subtest: vcs0
[778.431679] Dynamic subtest vcs0: SUCCESS (2.047s)
[778.478599] Starting dynamic subtest: vcs1
[780.482615] Dynamic subtest vcs1: SUCCESS (2.046s)
[780.531637] Starting dynamic subtest: vecs0
[782.532488] Dynamic subtest vecs0: SUCCESS (2.046s)
[782.581149] Starting dynamic subtest: ccs0
[784.585466] Dynamic subtest ccs0: SUCCESS (2.044s)
[784.588879] Subtest u-fairslice: SUCCESS (12.313s)
[784.996327] [086/142] (256s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-gtt)
[785.522193] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-gtt
[785.529208] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[785.694563] [087/142] (256s left) perf_pmu (idle)
[786.097666] Starting subtest: idle
[786.101701] Starting dynamic subtest: rcs0
[786.642473] Dynamic subtest rcs0: SUCCESS (0.541s)
[786.691752] Starting dynamic subtest: bcs0
[787.166579] Dynamic subtest bcs0: SUCCESS (0.515s)
[787.215193] Starting dynamic subtest: vcs0
[787.692297] Dynamic subtest vcs0: SUCCESS (0.519s)
[787.741946] Starting dynamic subtest: vcs1
[788.217680] Dynamic subtest vcs1: SUCCESS (0.523s)
[788.267058] Starting dynamic subtest: vecs0
[788.741893] Dynamic subtest vecs0: SUCCESS (0.519s)
[788.790694] Starting dynamic subtest: ccs0
[789.264980] Dynamic subtest ccs0: SUCCESS (0.517s)
[789.268914] Subtest idle: SUCCESS (3.160s)
[789.454431] [088/142] (252s left) gen3_render_mixed_blits
[789.960725] [089/142] (251s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[790.473980] Starting subtest: addfb25-y-tiled-small-legacy
[790.481218] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[790.652681] [090/142] (251s left) gem_softpin (allocator-basic)
[791.056854] Starting subtest: allocator-basic
[791.592670] Subtest allocator-basic: SUCCESS (0.534s)
[791.819993] [091/142] (250s left) kms_ccs (pipe-d-crc-primary-basic-y_tiled_gen12_mc_ccs)
[793.455347] Starting subtest: pipe-D-crc-primary-basic-y_tiled_gen12_mc_ccs
[793.462432] Subtest pipe-D-crc-primary-basic-y_tiled_gen12_mc_ccs: SKIP (0.002s)
[794.210447] [092/142] (248s left) kms_ccs (pipe-a-bad-rotation-90-y_tiled_ccs)
[795.143130] Starting subtest: pipe-A-bad-rotation-90-y_tiled_ccs
[795.150414] Subtest pipe-A-bad-rotation-90-y_tiled_ccs: SKIP (0.000s)
[796.709874] [093/142] (245s left) i915_pm_backlight (fade-with-dpms)
[797.187378] Starting subtest: fade-with-dpms
[799.757369] Starting dynamic subtest: eDP-1
[802.695372] Dynamic subtest eDP-1: SUCCESS (2.935s)
[802.698938] Subtest fade-with-dpms: SUCCESS (5.506s)
[804.011717] [094/142] (238s left) gen9_exec_parse (bb-start-param)
[804.410449] Subtest bb-start-param: SKIP (0.000s)
[804.536873] [095/142] (237s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-mmap-wc)
[805.056847] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-mmap-wc
[814.360159] Subtest fbc-1p-offscren-pri-shrfb-draw-mmap-wc: SUCCESS (9.313s)
[814.650092] [096/142] (227s left) gem_exec_whisper (basic-queues-forked)
[815.075517] Starting subtest: basic-queues-forked
[847.220193] Subtest basic-queues-forked: SUCCESS (32.139s)
[847.534622] [097/142] (195s left) gem_exec_schedule (smoketest-all)
[847.985316] Starting subtest: smoketest-all
[880.874095] Subtest smoketest-all: SUCCESS (32.820s)
[881.683329] [098/142] (161s left) kms_vblank (pipe-b-wait-forked)
[882.166356] Starting subtest: pipe-B-wait-forked
[885.891192] Subtest pipe-B-wait-forked: SUCCESS (3.725s)
[886.855757] [099/142] (156s left) gem_exec_balancer (fairslice)
[887.279118] Starting subtest: fairslice
[893.436270] Subtest fairslice: SUCCESS (6.156s)
[894.531703] [100/142] (148s left) gem_lmem_swapping (heavy-random)
[894.932995] Subtest heavy-random: SKIP (0.000s)
[895.073553] [101/142] (148s left) kms_cursor_crc (cursor-offscreen-32x32)
[895.726544] Starting subtest: cursor-offscreen-32x32
[896.052311] Subtest cursor-offscreen-32x32: SKIP (0.314s)
[897.012532] [102/142] (146s left) kms_ccs (pipe-c-crc-primary-rotation-180-y_tiled_gen12_rc_ccs)
[898.324835] Starting subtest: pipe-C-crc-primary-rotation-180-y_tiled_gen12_rc_ccs
[898.332664] Subtest pipe-C-crc-primary-rotation-180-y_tiled_gen12_rc_ccs: SKIP (0.001s)
[898.926619] [103/142] (144s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt)
[899.431809] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt
[910.300302] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (10.871s)
[910.515729] [104/142] (132s left) gem_pxp (reject-modify-context-protection-off-3)
[913.436486] Subtest reject-modify-context-protection-off-3: SKIP (0.000s)
[913.516717] [105/142] (129s left) gem_exec_fence (keep-in-fence)
[913.803703] Starting subtest: keep-in-fence
[913.806432] Starting dynamic subtest: rcs0
[913.885234] Dynamic subtest rcs0: SUCCESS (0.081s)
[913.944546] Starting dynamic subtest: bcs0
[913.988455] Dynamic subtest bcs0: SUCCESS (0.099s)
[914.047128] Starting dynamic subtest: vcs0
[915.507136] Dynamic subtest vcs0: SUCCESS (1.513s)
[915.560052] Starting dynamic subtest: vcs1
[917.495916] Dynamic subtest vcs1: SUCCESS (1.985s)
[917.549395] Starting dynamic subtest: vecs0
[919.540565] Dynamic subtest vecs0: SUCCESS (2.040s)
[919.595126] Starting dynamic subtest: ccs0
[921.283978] Dynamic subtest ccs0: SUCCESS (1.739s)
[921.287872] Subtest keep-in-fence: SUCCESS (7.481s)
[923.701217] [106/142] (119s left) gem_vm_create (invalid-create)
[924.107354] Starting subtest: invalid-create
[924.116419] Subtest invalid-create: SUCCESS (0.004s)
[924.321647] [107/142] (119s left) gem_madvise (dontneed-before-pwrite)
[924.695062] Starting subtest: dontneed-before-pwrite
[924.712874] Subtest dontneed-before-pwrite: SKIP (0.010s)
[924.858206] [108/142] (118s left) gem_exec_fence (syncobj-unused-fence)
[926.912825] Starting subtest: syncobj-unused-fence
[926.931447] Subtest syncobj-unused-fence: SUCCESS (0.018s)
[927.751352] [109/142] (116s left) drm_fdinfo (all-busy-idle-check-all)
[928.158901] Starting subtest: all-busy-idle-check-all
[928.177453] Subtest all-busy-idle-check-all: SKIP (0.016s)
[928.355426] [110/142] (115s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-wc)
[928.904406] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-wc
[928.911722] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.001s)
[929.098829] [111/142] (114s left) syncobj_timeline (single-wait-all-for-submit-available-signaled)
[929.486854] Starting subtest: single-wait-all-for-submit-available-signaled
[929.490693] Subtest single-wait-all-for-submit-available-signaled: SUCCESS (0.001s)
[929.641475] [112/142] (114s left) kms_sequence (queue-busy)
[930.132631] Starting subtest: queue-busy
[930.142439] Starting dynamic subtest: eDP-1-pipe-A
[932.351572] Dynamic subtest eDP-1-pipe-A: SUCCESS (2.211s)
[932.414609] Starting dynamic subtest: eDP-1-pipe-B
[935.298357] Dynamic subtest eDP-1-pipe-B: SUCCESS (2.934s)
[935.358216] Starting dynamic subtest: eDP-1-pipe-C
[938.046777] Dynamic subtest eDP-1-pipe-C: SUCCESS (2.736s)
[938.110981] Starting dynamic subtest: eDP-1-pipe-D
[941.540343] Dynamic subtest eDP-1-pipe-D: SUCCESS (3.483s)
[941.543841] Subtest queue-busy: SUCCESS (11.406s)
[942.505259] [113/142] (101s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[943.028880] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[943.036340] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[943.199349] [114/142] (101s left) kms_atomic_interruptible (legacy-setmode)
[943.698460] Starting subtest: legacy-setmode
[943.707116] Starting dynamic subtest: eDP-1-pipe-A
[951.031135] Dynamic subtest eDP-1-pipe-A: SUCCESS (7.322s)
[951.035187] Subtest legacy-setmode: SUCCESS (7.333s)
[951.989272] [115/142] ( 92s left) kms_chamelium_edid (dp-mode-timings)
[952.865166] Subtest dp-mode-timings: SKIP (0.000s)
[953.742861] [116/142] ( 90s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[954.307679] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[954.314816] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[954.487324] [117/142] ( 90s left) kms_psr2_su (page_flip-nv12)
[954.860422] Subtest page_flip-NV12: SKIP (0.000s)
[954.987520] [118/142] ( 89s left) prime_vgem (basic-fence-read)
[955.416630] Starting subtest: basic-fence-read
[955.451429] Subtest basic-fence-read: SKIP (0.033s)
[955.629513] [119/142] ( 89s left) gem_exec_reloc (basic-cpu-read)
[956.037748] Subtest basic-cpu-read: SKIP (0.000s)
[956.185388] [120/142] ( 88s left) kms_vblank (pipe-d-ts-continuation-modeset)
[956.667572] Starting subtest: pipe-D-ts-continuation-modeset
[960.474988] Subtest pipe-D-ts-continuation-modeset: SUCCESS (3.809s)
[961.441216] [121/142] ( 83s left) kms_vblank (pipe-c-wait-forked-busy-hang)
[961.915578] Starting subtest: pipe-C-wait-forked-busy-hang
[977.824138] Subtest pipe-C-wait-forked-busy-hang: SUCCESS (15.905s)
[978.781248] [122/142] ( 66s left) kms_async_flips (test-time-stamp)
[979.264278] Starting subtest: test-time-stamp
[981.455528] Starting dynamic subtest: pipe-A-eDP-1
[982.717492] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.260s)
[984.848775] Starting dynamic subtest: pipe-B-eDP-1
[985.354178] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.559s)
[987.214287] Starting dynamic subtest: pipe-C-eDP-1
[987.672825] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.512s)
[988.871735] Starting dynamic subtest: pipe-D-eDP-1
[989.133044] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.317s)
[989.135924] Subtest test-time-stamp: SUCCESS (9.871s)
[990.750062] [123/142] ( 54s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[991.272633] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[991.279397] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[991.463450] [124/142] ( 53s left) kms_ccs (pipe-c-random-ccs-data-4_tiled_mtl_mc_ccs)
[992.917361] Starting subtest: pipe-C-random-ccs-data-4_tiled_mtl_mc_ccs
[997.080422] Subtest pipe-C-random-ccs-data-4_tiled_mtl_mc_ccs: SUCCESS (4.163s)
[998.521740] [125/142] ( 46s left) gem_mmap_gtt (cpuset-basic-small-copy)
[998.900879] Subtest cpuset-basic-small-copy: SKIP (0.000s)
[999.027282] [126/142] ( 46s left) perf_pmu (enable-race)
[999.417535] Starting subtest: enable-race
[999.421302] Starting dynamic subtest: rcs0
[1012.389167] Dynamic subtest rcs0: SUCCESS (12.970s)
[1012.449267] Starting dynamic subtest: bcs0
[1025.363526] Dynamic subtest bcs0: SUCCESS (12.970s)
[1025.423807] Starting dynamic subtest: vcs0
[1040.497760] Dynamic subtest vcs0: SUCCESS (15.128s)
[1040.551093] Starting dynamic subtest: vcs1
[1056.498236] Dynamic subtest vcs1: SUCCESS (15.996s)
[1056.551571] Starting dynamic subtest: vecs0
[1072.498653] Dynamic subtest vecs0: SUCCESS (15.996s)
[1072.553371] Starting dynamic subtest: ccs0
[1085.467220] Dynamic subtest ccs0: SUCCESS (12.965s)
[1085.471520] Subtest enable-race: SUCCESS (86.053s)
[1085.660516] Overall timeout time exceeded, stopping.
[1085.661798] Closing watchdogs
Done.