[46.562603] Initializing watchdogs [46.562929] /dev/watchdog0 [46.580109] [001/142] (960s left) kms_sysfs_edid_timing [48.073070] [002/142] (959s left) syncobj_wait (wait-delayed-signal) [48.286469] Starting subtest: wait-delayed-signal [48.391169] Subtest wait-delayed-signal: SUCCESS (0.103s) [48.472075] [003/142] (958s left) gem_exec_fence (syncobj-invalid-wait) [49.269363] Starting subtest: syncobj-invalid-wait [49.270659] Subtest syncobj-invalid-wait: SUCCESS (0.001s) [49.605774] [004/142] (957s left) kms_ccs (pipe-a-bad-pixel-format-y_tiled_gen12_mc_ccs) [49.914582] Starting subtest: pipe-A-bad-pixel-format-y_tiled_gen12_mc_ccs [49.917129] Subtest pipe-A-bad-pixel-format-y_tiled_gen12_mc_ccs: SKIP (0.000s) [50.597695] [005/142] (956s left) kms_psr (psr2_primary_page_flip) [50.807476] Subtest psr2_primary_page_flip: SKIP (0.000s) [50.862400] [006/142] (956s left) device_reset (unbind-reset-rebind) [51.101109] Starting subtest: unbind-reset-rebind [51.951427] Subtest unbind-reset-rebind: SUCCESS (0.849s) [52.449121] [007/142] (954s left) gem_exec_gttfill (engines) [52.715293] Starting subtest: engines [52.716745] Starting dynamic subtest: rcs0 [88.384773] Dynamic subtest rcs0: SUCCESS (35.666s) [88.403040] Starting dynamic subtest: bcs0 [126.403690] Dynamic subtest bcs0: SUCCESS (38.018s) [126.420722] Starting dynamic subtest: vcs0 [162.963066] Dynamic subtest vcs0: SUCCESS (36.559s) [162.980201] Starting dynamic subtest: vecs0 [199.265149] Dynamic subtest vecs0: SUCCESS (36.302s) [199.266491] Subtest engines: SUCCESS (146.548s) [199.472847] [008/142] (807s left) gem_lmem_swapping (heavy-verify-multi) [199.817026] Subtest heavy-verify-multi: SKIP (0.000s) [199.865522] [009/142] (807s left) kms_plane_multiple (tiling-x) [200.189780] Starting subtest: tiling-x [200.191387] Starting dynamic subtest: pipe-A-DP-1 [202.133216] Dynamic subtest pipe-A-DP-1: SUCCESS (1.941s) [202.151182] Starting dynamic subtest: pipe-B-DP-1 [204.298876] Dynamic subtest pipe-B-DP-1: SUCCESS (2.166s) [204.320254] Starting dynamic subtest: pipe-C-DP-1 [206.399095] Dynamic subtest pipe-C-DP-1: SUCCESS (2.095s) [206.400446] Subtest tiling-x: SUCCESS (6.209s) [206.753948] [010/142] (800s left) gem_render_copy (yf-tiled-ccs-to-y-tiled-ccs) [206.981665] Starting subtest: yf-tiled-ccs-to-y-tiled-ccs [206.983192] Starting dynamic subtest: smem [207.323125] Dynamic subtest smem: SUCCESS (0.337s) [207.324451] Subtest yf-tiled-ccs-to-y-tiled-ccs: SUCCESS (0.338s) [207.522176] [011/142] (799s left) syncobj_basic (bad-create-flags) [207.720055] Starting subtest: bad-create-flags [207.721833] Subtest bad-create-flags: SUCCESS (0.000s) [207.803136] [012/142] (799s left) gem_eio (wait-wedge-immediate) [208.034868] Starting subtest: wait-wedge-immediate [209.186750] Subtest wait-wedge-immediate: SUCCESS (1.155s) [209.386995] [013/142] (798s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-pwrite) [209.699655] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-pwrite [209.702259] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s) [209.803987] [014/142] (797s left) gem_exec_balancer (invalid-balancer) [210.032632] Starting subtest: invalid-balancer [213.749565] Subtest invalid-balancer: SUCCESS (3.715s) [217.653440] [015/142] (789s left) gem_exec_reloc (basic-write-wc-active) [217.866516] Starting subtest: basic-write-wc-active [218.009157] Subtest basic-write-wc-active: SUCCESS (0.141s) [218.186707] [016/142] (789s left) kms_ccs (pipe-b-bad-pixel-format-4_tiled_dg2_mc_ccs) [218.664944] Starting subtest: pipe-B-bad-pixel-format-4_tiled_dg2_mc_ccs [218.667619] Subtest pipe-B-bad-pixel-format-4_tiled_dg2_mc_ccs: SKIP (0.000s) [219.222828] [017/142] (788s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-msflip-blt) [219.539298] Starting subtest: fbcpsr-1p-primscrn-shrfb-msflip-blt [219.541793] Subtest fbcpsr-1p-primscrn-shrfb-msflip-blt: SKIP (0.000s) [219.638628] [018/142] (788s left) kms_draw_crc (draw-method-render) [219.946723] Starting subtest: draw-method-render [219.948192] Starting dynamic subtest: xrgb8888-untiled [220.315549] Dynamic subtest xrgb8888-untiled: SUCCESS (0.371s) [220.340982] Starting dynamic subtest: rgb565-untiled [220.649684] Dynamic subtest rgb565-untiled: SUCCESS (0.330s) [220.672040] Starting dynamic subtest: xrgb2101010-untiled [220.998793] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.350s) [221.018294] Starting dynamic subtest: xrgb8888-xtiled [221.215528] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.216s) [221.234521] Starting dynamic subtest: rgb565-xtiled [221.399690] Dynamic subtest rgb565-xtiled: SUCCESS (0.183s) [221.418252] Starting dynamic subtest: xrgb2101010-xtiled [221.615576] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.216s) [221.634283] Starting dynamic subtest: xrgb8888-ytiled [221.832266] Dynamic subtest xrgb8888-ytiled: SUCCESS (0.216s) [221.851446] Starting dynamic subtest: rgb565-ytiled [222.015586] Dynamic subtest rgb565-ytiled: SUCCESS (0.183s) [222.034034] Starting dynamic subtest: xrgb2101010-ytiled [222.218679] Dynamic subtest xrgb2101010-ytiled: SUCCESS (0.199s) [222.220115] Subtest draw-method-render: SUCCESS (2.271s) [222.339384] [019/142] (785s left) gem_vm_create (isolation) [222.550108] Starting subtest: isolation [222.562160] Subtest isolation: SUCCESS (0.012s) [222.673227] [020/142] (785s left) drm_fdinfo (busy-check-all) [222.882172] Starting subtest: busy-check-all [222.883495] Starting dynamic subtest: rcs0 [223.417702] Dynamic subtest rcs0: SUCCESS (0.536s) [223.436328] Starting dynamic subtest: bcs0 [223.954612] Dynamic subtest bcs0: SUCCESS (0.536s) [223.975885] Starting dynamic subtest: vcs0 [224.483589] Dynamic subtest vcs0: SUCCESS (0.528s) [224.503132] Starting dynamic subtest: vecs0 [225.009918] Dynamic subtest vecs0: SUCCESS (0.526s) [225.011503] Subtest busy-check-all: SUCCESS (2.129s) [225.087755] [021/142] (782s left) gem_softpin (allocator-evict) [225.299431] Starting subtest: allocator-evict [225.300814] Starting dynamic subtest: rcs0 [260.451165] Dynamic subtest rcs0: SUCCESS (35.150s) [260.469183] Starting dynamic subtest: bcs0 [297.704203] Dynamic subtest bcs0: SUCCESS (37.252s) [297.723265] Starting dynamic subtest: vcs0 [334.799219] Dynamic subtest vcs0: SUCCESS (37.094s) [334.817439] Starting dynamic subtest: vecs0 [371.831059] Dynamic subtest vecs0: SUCCESS (37.031s) [371.832722] Subtest allocator-evict: SUCCESS (146.531s) [371.926361] [022/142] (635s left) gem_userptr_blits (stress-mm-invalidate-close-overlap) [372.294910] Starting subtest: stress-mm-invalidate-close-overlap [374.447191] Subtest stress-mm-invalidate-close-overlap: SUCCESS (2.149s) [374.590082] [023/142] (633s left) gem_exec_schedule (submit-early-slice) [374.846446] Starting subtest: submit-early-slice [374.847945] Starting dynamic subtest: rcs0 [374.926150] Dynamic subtest rcs0: SUCCESS (0.078s) [374.949939] Starting dynamic subtest: bcs0 [374.987924] Dynamic subtest bcs0: SUCCESS (0.062s) [375.014691] Starting dynamic subtest: vcs0 [375.051568] Dynamic subtest vcs0: SUCCESS (0.062s) [375.077691] Starting dynamic subtest: vecs0 [375.110707] Dynamic subtest vecs0: SUCCESS (0.059s) [375.112183] Subtest submit-early-slice: SUCCESS (0.264s) [375.457078] [024/142] (632s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted-signaled) [375.663946] Starting subtest: multi-wait-all-for-submit-available-submitted-signaled [375.667830] Subtest multi-wait-all-for-submit-available-submitted-signaled: SUCCESS (0.002s) [375.755440] [025/142] (632s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-render) [376.078666] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-render [378.351271] Subtest fbc-1p-primscrn-pri-indfb-draw-render: SUCCESS (2.276s) [378.472514] [026/142] (629s left) kms_ccs (pipe-d-crc-primary-rotation-180-4_tiled_dg2_rc_ccs_cc) [379.139760] Subtest pipe-D-crc-primary-rotation-180-4_tiled_dg2_rc_ccs_cc: SKIP (0.000s) [379.458198] [027/142] (628s left) kms_pipe_b_c_ivb (disable-pipe-b-enable-pipe-c) [379.662748] Subtest disable-pipe-B-enable-pipe-C: SKIP (0.000s) [379.723053] [028/142] (628s left) vc4/vc4_purgeable_bo (access-purged-bo-mem) [379.985912] Subtest access-purged-bo-mem: SKIP (0.000s) [380.058080] [029/142] (628s left) perf_pmu (busy-accuracy-50) [380.270181] Starting subtest: busy-accuracy-50 [380.271709] Starting dynamic subtest: rcs0 [381.827149] Dynamic subtest rcs0: SUCCESS (1.553s) [381.847213] Starting dynamic subtest: bcs0 [383.381268] Dynamic subtest bcs0: SUCCESS (1.551s) [383.400932] Starting dynamic subtest: vcs0 [384.930848] Dynamic subtest vcs0: SUCCESS (1.552s) [384.950823] Starting dynamic subtest: vecs0 [386.484746] Dynamic subtest vecs0: SUCCESS (1.552s) [386.486263] Subtest busy-accuracy-50: SUCCESS (6.213s) [386.576684] [030/142] (621s left) kms_ccs (pipe-c-missing-ccs-buffer-4_tiled_mtl_mc_ccs) [387.197380] Starting subtest: pipe-C-missing-ccs-buffer-4_tiled_mtl_mc_ccs [387.200034] Subtest pipe-C-missing-ccs-buffer-4_tiled_mtl_mc_ccs: SKIP (0.000s) [387.558663] [031/142] (620s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling) [387.855931] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling [387.857321] Starting dynamic subtest: pipe-A-valid-mode [388.635331] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.776s) [388.636888] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SUCCESS (0.779s) [388.986182] [032/142] (619s left) i915_query (test-query-geometry-subslices) [389.191657] Starting subtest: test-query-geometry-subslices [389.194476] Subtest test-query-geometry-subslices: SKIP (0.000s) [389.285937] [033/142] (619s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-wc) [389.595460] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-wc [389.597989] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s) [389.703269] [034/142] (618s left) kms_lease (atomic_implicit_crtc) [390.004964] Starting subtest: atomic_implicit_crtc [390.007177] Starting dynamic subtest: pipe-A-DP-1 [390.009425] Dynamic subtest pipe-A-DP-1: SUCCESS (0.004s) [390.044450] Starting dynamic subtest: pipe-B-DP-1 [390.046542] Dynamic subtest pipe-B-DP-1: SUCCESS (0.021s) [390.069384] Starting dynamic subtest: pipe-C-DP-1 [390.070602] Dynamic subtest pipe-C-DP-1: SUCCESS (0.020s) [390.071842] Subtest atomic_implicit_crtc: SUCCESS (0.054s) [390.136265] [035/142] (618s left) prime_vgem (basic-write) [390.357043] Starting subtest: basic-write [390.418307] Subtest basic-write: SUCCESS (0.061s) [390.503061] [036/142] (617s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite) [390.806616] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite [390.809331] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s) [390.919536] [037/142] (617s left) gem_exec_reloc (basic-softpin) [391.135791] Starting subtest: basic-softpin [391.144488] Subtest basic-softpin: SUCCESS (0.006s) [391.319661] [038/142] (617s left) kms_ccs (pipe-b-crc-sprite-planes-basic-y_tiled_gen12_mc_ccs) [391.890084] Starting subtest: pipe-B-crc-sprite-planes-basic-y_tiled_gen12_mc_ccs [391.896639] Subtest pipe-B-crc-sprite-planes-basic-y_tiled_gen12_mc_ccs: SKIP (0.005s) [392.326797] [039/142] (616s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [392.635946] Starting subtest: basic-flip-after-cursor-legacy [393.917793] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.282s) [394.256126] [040/142] (614s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip) [394.585707] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip [394.614810] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.029s) [394.737683] [041/142] (613s left) kms_flip (absolute-wf_vblank-interruptible) [395.046472] Starting subtest: absolute-wf_vblank-interruptible [395.049047] Starting dynamic subtest: A-DP1 [405.578424] Dynamic subtest A-DP1: SUCCESS (10.529s) [405.600235] Starting dynamic subtest: B-DP1 [416.034369] Dynamic subtest B-DP1: SUCCESS (10.455s) [416.057422] Starting dynamic subtest: C-DP1 [426.651265] Dynamic subtest C-DP1: SUCCESS (10.615s) [426.654687] Subtest absolute-wf_vblank-interruptible: SUCCESS (31.609s) [427.108201] [042/142] (581s left) gem_ctx_param (invalid-get-engines) [427.312387] Starting subtest: invalid-get-engines [427.313927] Subtest invalid-get-engines: SUCCESS (0.000s) [427.405195] [043/142] (581s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-blt) [427.711305] Starting subtest: fbcpsr-rgb101010-draw-blt [427.714028] Subtest fbcpsr-rgb101010-draw-blt: SKIP (0.000s) [427.821919] [044/142] (580s left) kms_plane (plane-position-hole) [428.119350] Starting subtest: plane-position-hole [428.120730] Starting dynamic subtest: pipe-A-planes [429.583349] Dynamic subtest pipe-A-planes: SUCCESS (1.465s) [429.609238] Starting dynamic subtest: pipe-B-planes [431.445417] Dynamic subtest pipe-B-planes: SUCCESS (1.858s) [431.446728] Subtest plane-position-hole: SUCCESS (3.331s) [431.784366] [045/142] (576s left) i915_selftest (perf) [433.475153] Starting subtest: perf [433.477346] Starting dynamic subtest: engine_cs [435.294692] Dynamic subtest engine_cs: SUCCESS (1.818s) [435.310828] Starting dynamic subtest: request [439.676435] Dynamic subtest request: SUCCESS (4.380s) [439.690352] Starting dynamic subtest: migrate [441.187441] Dynamic subtest migrate: SUCCESS (1.509s) [441.204456] Starting dynamic subtest: region [443.845424] Dynamic subtest region: SUCCESS (2.656s) [443.846928] Subtest perf: SUCCESS (10.371s) [443.880985] [046/142] (564s left) gem_exec_whisper (basic-forked-all) [445.354590] Starting subtest: basic-forked-all [467.720084] Subtest basic-forked-all: SUCCESS (22.368s) [467.988553] [047/142] (540s left) kms_frontbuffer_tracking (fbc-stridechange) [468.298682] Starting subtest: fbc-stridechange [469.952601] Subtest fbc-stridechange: SUCCESS (1.654s) [470.090473] [048/142] (538s left) kms_cursor_crc (cursor-offscreen-256x85) [470.400500] Starting subtest: cursor-offscreen-256x85 [470.413805] Starting dynamic subtest: pipe-A-DP-1 [472.923883] Dynamic subtest pipe-A-DP-1: SUCCESS (2.506s) [473.007170] Starting dynamic subtest: pipe-C-DP-1 [475.835027] Dynamic subtest pipe-C-DP-1: SUCCESS (2.844s) [475.836404] Subtest cursor-offscreen-256x85: SUCCESS (5.434s) [476.198550] [049/142] (532s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions) [476.495580] Starting subtest: cursorB-vs-flipA-atomic-transitions [476.500434] Subtest cursorB-vs-flipA-atomic-transitions: SKIP (0.004s) [476.594538] [050/142] (532s left) gem_ctx_param (non-root-set) [476.793727] Starting subtest: non-root-set [476.826509] Subtest non-root-set: SUCCESS (0.034s) [476.911226] [051/142] (532s left) kms_vblank (pipe-d-ts-continuation-idle-hang) [477.208973] Subtest pipe-D-ts-continuation-idle-hang: SKIP (0.000s) [477.294985] [052/142] (531s left) kms_vblank (pipe-b-query-forked) [477.593288] Starting subtest: pipe-B-query-forked [480.332800] Subtest pipe-B-query-forked: SUCCESS (2.740s) [480.666631] [053/142] (528s left) kms_cursor_legacy (cursorb-vs-flipa-varying-size) [480.970255] Starting subtest: cursorB-vs-flipA-varying-size [480.972718] Subtest cursorB-vs-flipA-varying-size: SKIP (0.004s) [481.065626] [054/142] (528s left) gem_busy (busy) [481.290658] Starting subtest: busy [481.292128] Starting dynamic subtest: all-engines [481.381292] Dynamic subtest all-engines: SUCCESS (0.089s) [481.401255] Starting dynamic subtest: rcs0 [481.402458] Dynamic subtest rcs0: SUCCESS (0.013s) [481.426379] Starting dynamic subtest: bcs0 [481.427595] Dynamic subtest bcs0: SUCCESS (0.014s) [481.447078] Starting dynamic subtest: vcs0 [481.448464] Dynamic subtest vcs0: SUCCESS (0.018s) [481.466856] Starting dynamic subtest: vecs0 [481.468198] Dynamic subtest vecs0: SUCCESS (0.010s) [481.469423] Subtest busy: SUCCESS (0.156s) [481.816264] [055/142] (527s left) kms_flip (2x-blocking-absolute-wf_vblank) [482.103468] Starting subtest: 2x-blocking-absolute-wf_vblank [482.106069] Subtest 2x-blocking-absolute-wf_vblank: SKIP (0.003s) [482.217957] [056/142] (526s left) kms_vblank (pipe-c-wait-forked) [482.520463] Starting subtest: pipe-C-wait-forked [485.358998] Subtest pipe-C-wait-forked: SUCCESS (2.840s) [485.700410] [057/142] (523s left) kms_color (ctm-green-to-red) [485.993628] Starting subtest: ctm-green-to-red [485.994958] Starting dynamic subtest: pipe-A [486.524158] Dynamic subtest pipe-A: SUCCESS (0.528s) [486.549195] Starting dynamic subtest: pipe-B [487.324763] Dynamic subtest pipe-B: SUCCESS (0.797s) [487.361544] Starting dynamic subtest: pipe-C [488.281190] Dynamic subtest pipe-C: SUCCESS (0.956s) [488.282501] Subtest ctm-green-to-red: SUCCESS (2.287s) [488.620707] [058/142] (520s left) kms_plane_scaling (planes-downscale-factor-0-75) [488.924750] Starting subtest: planes-downscale-factor-0-75 [488.926213] Starting dynamic subtest: pipe-A-DP-1 [489.029613] Dynamic subtest pipe-A-DP-1: SUCCESS (0.104s) [489.059933] Starting dynamic subtest: pipe-B-DP-1 [489.443386] Dynamic subtest pipe-B-DP-1: SUCCESS (0.408s) [489.477081] Starting dynamic subtest: pipe-C-DP-1 [489.552350] Dynamic subtest pipe-C-DP-1: SKIP (0.101s) [489.553692] Subtest planes-downscale-factor-0-75: SUCCESS (0.629s) [489.957630] [059/142] (519s left) i915_module_load (load) [490.157790] Starting subtest: load [490.160439] Subtest load: SKIP (0.003s) [490.223731] [060/142] (519s left) kms_selftest (drm_plane) [490.415958] Starting subtest: drm_plane [490.422338] Subtest drm_plane: SKIP (0.005s) [490.490343] [061/142] (518s left) gem_userptr_blits (coherency-unsync) [490.702261] Subtest coherency-unsync: SKIP (0.000s) [490.857963] [062/142] (518s left) kms_big_fb (y-tiled-32bpp-rotate-90) [491.176035] Starting subtest: y-tiled-32bpp-rotate-90 [496.133099] Subtest y-tiled-32bpp-rotate-90: SUCCESS (4.958s) [496.674937] [063/142] (512s left) gem_mmap_offset (open-flood) [496.875485] Starting subtest: open-flood [518.436859] Subtest open-flood: SUCCESS (21.564s) [518.540817] [064/142] (490s left) vc4/vc4_wait_bo (bad-bo) [518.802408] Subtest bad-bo: SKIP (0.000s) [518.873815] [065/142] (490s left) kms_frontbuffer_tracking (fbc-farfromfence-mmap-gtt) [519.183267] Starting subtest: fbc-farfromfence-mmap-gtt [521.765603] Subtest fbc-farfromfence-mmap-gtt: SUCCESS (2.584s) [521.906928] [066/142] (487s left) gem_userptr_blits (sd-probe) [522.116124] Starting subtest: sd-probe [522.117757] Subtest sd-probe: SUCCESS (0.003s) [522.291304] [067/142] (487s left) kms_chamelium_frames (hdmi-frame-dump) [522.700812] Subtest hdmi-frame-dump: SKIP (0.000s) [523.001918] [068/142] (486s left) gem_exec_flush (basic-uc-pro-default) [523.227075] Starting subtest: basic-uc-pro-default [528.702961] Subtest basic-uc-pro-default: SUCCESS (5.476s) [528.914725] [069/142] (480s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-render) [529.218972] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-render [529.222148] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s) [529.330847] [070/142] (480s left) kms_vblank (pipe-a-wait-forked-hang) [529.634777] Starting subtest: pipe-A-wait-forked-hang [537.003810] Subtest pipe-A-wait-forked-hang: SUCCESS (7.370s) [537.332899] [071/142] (472s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-wc) [537.631963] Starting subtest: fbc-rgb101010-draw-mmap-wc [540.274745] Subtest fbc-rgb101010-draw-mmap-wc: SUCCESS (2.643s) [540.414826] [072/142] (469s left) kms_chamelium_color (ctm-negative) [540.722503] Subtest ctm-negative: SKIP (0.000s) [540.781593] [073/142] (469s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-mmap-cpu) [541.090789] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-mmap-cpu [542.926102] Subtest fbc-1p-offscren-pri-shrfb-draw-mmap-cpu: SUCCESS (1.837s) [543.065642] [074/142] (466s left) vgem_basic (debugfs) [543.276119] Starting subtest: debugfs [543.277527] Subtest debugfs: SUCCESS (0.002s) [543.348331] [075/142] (466s left) gem_pwrite (basic-exhaustion) [543.552309] Starting subtest: basic-exhaustion [564.718553] Subtest basic-exhaustion: SUCCESS (21.167s) [564.834845] [076/142] (445s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-async-flip) [565.148850] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip [565.151347] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s) [565.266847] [077/142] (444s left) kms_legacy_colorkey (invalid-plane) [565.569517] Starting subtest: invalid-plane [565.571347] Starting dynamic subtest: zero-id [565.572972] Dynamic subtest zero-id: SUCCESS (0.000s) [565.593186] Starting dynamic subtest: outrange-id [565.594420] Dynamic subtest outrange-id: SUCCESS (0.000s) [565.595678] Subtest invalid-plane: SUCCESS (0.005s) [565.666358] [078/142] (444s left) gem_userptr_blits (readonly-unsync) [565.877537] Subtest readonly-unsync: SKIP (0.000s) [566.049764] [079/142] (444s left) api_intel_bb (bb-with-allocator) [566.248734] Starting subtest: bb-with-allocator [566.261142] Subtest bb-with-allocator: SUCCESS (0.011s) [566.381753] [080/142] (443s left) syncobj_timeline (wait-all-for-submit-complex) [566.587921] Starting subtest: wait-all-for-submit-complex [566.808562] Subtest wait-all-for-submit-complex: SUCCESS (0.221s) [566.898697] [081/142] (443s left) kms_content_protection (legacy) [567.232391] Starting subtest: legacy [567.430264] Starting dynamic subtest: pipe-A-DP-1 [688.378673] Per-test timeout exceeded. Killing the current test with SIGQUIT. [689.103823] Closing watchdogs [689.110058] Initializing watchdogs [689.110231] /dev/watchdog0 [689.134779] [082/142] (321s left) i915_hangman (engine-error-state-capture) [689.361627] Starting subtest: engine-error-state-capture [689.363170] Starting dynamic subtest: rcs0 [693.760064] Dynamic subtest rcs0: SUCCESS (4.397s) [693.785455] Starting dynamic subtest: bcs0 [697.716853] Dynamic subtest bcs0: SUCCESS (3.955s) [697.739842] Starting dynamic subtest: vcs0 [701.762316] Dynamic subtest vcs0: SUCCESS (4.043s) [701.787400] Starting dynamic subtest: vecs0 [705.726193] Dynamic subtest vecs0: SUCCESS (3.958s) [705.727948] Subtest engine-error-state-capture: SUCCESS (16.364s) [705.848851] [083/142] (304s left) prime_vgem (coherency-blt) [706.080148] Starting subtest: coherency-blt [709.695771] Subtest coherency-blt: SUCCESS (3.612s) [709.816379] [084/142] (300s left) kms_flip (wf_vblank-ts-check-interruptible) [710.117867] Starting subtest: wf_vblank-ts-check-interruptible [710.122584] Starting dynamic subtest: A-DP1 [737.318880] Dynamic subtest A-DP1: SUCCESS (27.196s) [737.344105] Starting dynamic subtest: B-DP1 [764.169283] Dynamic subtest B-DP1: SUCCESS (26.847s) [764.194226] Starting dynamic subtest: C-DP1 [791.093540] Dynamic subtest C-DP1: SUCCESS (26.919s) [791.097946] Subtest wf_vblank-ts-check-interruptible: SUCCESS (80.979s) [808.061790] [085/142] (202s left) i915_pm_dc (dc9-dpms) [809.374941] Starting subtest: dc9-dpms [809.381881] Subtest dc9-dpms: SKIP (0.000s) [809.544337] [086/142] (200s left) kms_cursor_crc (cursor-rapid-movement-64x21) [809.862704] Starting subtest: cursor-rapid-movement-64x21 [809.898342] Starting dynamic subtest: pipe-A-DP-1 [810.399760] Dynamic subtest pipe-A-DP-1: SUCCESS (0.501s) [810.490071] Starting dynamic subtest: pipe-C-DP-1 [811.298416] Dynamic subtest pipe-C-DP-1: SUCCESS (0.833s) [811.299824] Subtest cursor-rapid-movement-64x21: SUCCESS (1.436s) [811.674552] [087/142] (198s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-downscaling) [811.978557] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-downscaling [811.980906] Starting dynamic subtest: pipe-A-valid-mode [812.076727] Dynamic subtest pipe-A-valid-mode: SKIP (0.099s) [812.079725] Subtest flip-32bpp-4tile-to-64bpp-4tile-downscaling: SKIP (0.103s) [812.401774] [088/142] (198s left) gem_ctx_persistence (heartbeat-hostile) [812.610717] Starting subtest: heartbeat-hostile [813.779713] Subtest heartbeat-hostile: SUCCESS (1.169s) [813.868552] [089/142] (196s left) gem_mmap_gtt (cpuset-big-copy-odd) [814.068035] Starting subtest: cpuset-big-copy-odd [837.654893] Subtest cpuset-big-copy-odd: SUCCESS (23.584s) [838.106659] [090/142] (172s left) kms_ccs (pipe-a-bad-aux-stride-yf_tiled_ccs) [838.707748] Starting subtest: pipe-A-bad-aux-stride-yf_tiled_ccs [838.712343] Subtest pipe-A-bad-aux-stride-yf_tiled_ccs: SUCCESS (0.001s) [839.243318] [091/142] (171s left) kms_vblank (pipe-b-ts-continuation-modeset) [839.545694] Starting subtest: pipe-B-ts-continuation-modeset [840.563530] Subtest pipe-B-ts-continuation-modeset: SUCCESS (1.018s) [840.898874] [092/142] (169s left) perf_pmu (semaphore-wait) [841.109979] Starting subtest: semaphore-wait [841.111604] Starting dynamic subtest: rcs0 [841.630732] Dynamic subtest rcs0: SUCCESS (0.517s) [841.655222] Starting dynamic subtest: bcs0 [842.148870] Dynamic subtest bcs0: SUCCESS (0.516s) [842.170419] Starting dynamic subtest: vcs0 [842.664612] Dynamic subtest vcs0: SUCCESS (0.513s) [842.686107] Starting dynamic subtest: vecs0 [843.180678] Dynamic subtest vecs0: SUCCESS (0.513s) [843.182407] Subtest semaphore-wait: SUCCESS (2.070s) [843.284490] [093/142] (167s left) v3d/v3d_create_bo (create-bo-0) [843.556705] Subtest create-bo-0: SKIP (0.000s) [843.633120] [094/142] (167s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-move) [843.946044] Starting subtest: psr-1p-primscrn-spr-indfb-move [843.948772] Subtest psr-1p-primscrn-spr-indfb-move: SKIP (0.000s) [844.049165] [095/142] (166s left) kms_big_fb (4-tiled-32bpp-rotate-270) [844.375670] Starting subtest: 4-tiled-32bpp-rotate-270 [844.378719] Subtest 4-tiled-32bpp-rotate-270: SKIP (0.000s) [844.482353] [096/142] (166s left) kms_ccs (pipe-b-random-ccs-data-y_tiled_ccs) [844.990752] Starting subtest: pipe-B-random-ccs-data-y_tiled_ccs [845.435648] Subtest pipe-B-random-ccs-data-y_tiled_ccs: SUCCESS (0.444s) [846.026348] [097/142] (164s left) gem_exec_fence (basic-wait) [846.390820] Starting subtest: basic-wait [846.392404] Starting dynamic subtest: rcs0 [846.420833] Dynamic subtest rcs0: SUCCESS (0.028s) [846.449065] Starting dynamic subtest: bcs0 [846.453802] Dynamic subtest bcs0: SUCCESS (0.029s) [846.477124] Starting dynamic subtest: vcs0 [846.481655] Dynamic subtest vcs0: SUCCESS (0.025s) [846.508252] Starting dynamic subtest: vecs0 [846.509450] Dynamic subtest vecs0: SUCCESS (0.020s) [846.510631] Subtest basic-wait: SUCCESS (0.114s) [847.195837] [098/142] (163s left) syncobj_timeline (invalid-single-wait-unsubmitted) [847.397920] Starting subtest: invalid-single-wait-unsubmitted [847.399691] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s) [847.492836] [099/142] (163s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-wc) [847.794901] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-wc [847.797833] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s) [847.909643] [100/142] (163s left) kms_big_fb (4-tiled-8bpp-rotate-270) [848.226491] Starting subtest: 4-tiled-8bpp-rotate-270 [848.230165] Subtest 4-tiled-8bpp-rotate-270: SKIP (0.000s) [848.344492] [101/142] (162s left) kms_frontbuffer_tracking (fbc-rgb565-draw-render) [848.655096] Starting subtest: fbc-rgb565-draw-render [850.883082] Subtest fbc-rgb565-draw-render: SUCCESS (2.228s) [851.010074] [102/142] (160s left) prime_self_import (basic-llseek-size) [851.204518] Starting subtest: basic-llseek-size [851.211305] Subtest basic-llseek-size: SUCCESS (0.007s) [851.326784] [103/142] (159s left) gem_exec_reloc (basic-write-wc) [851.538728] Starting subtest: basic-write-wc [851.555536] Subtest basic-write-wc: SUCCESS (0.017s) [851.743324] [104/142] (159s left) gem_ctx_shared (q-smoketest) [851.970339] Starting subtest: Q-smoketest [851.971846] Starting dynamic subtest: rcs0 [857.445696] Dynamic subtest rcs0: SUCCESS (5.465s) [857.466809] Starting dynamic subtest: bcs0 [862.942239] Dynamic subtest bcs0: SUCCESS (5.494s) [862.962805] Starting dynamic subtest: vcs0 [868.406141] Dynamic subtest vcs0: SUCCESS (5.462s) [868.426823] Starting dynamic subtest: vecs0 [873.881505] Dynamic subtest vecs0: SUCCESS (5.473s) [873.882870] Subtest Q-smoketest: SUCCESS (21.907s) [874.093613] [105/142] (137s left) kms_ccs (pipe-c-crc-primary-basic-y_tiled_gen12_rc_ccs) [874.684637] Starting subtest: pipe-C-crc-primary-basic-y_tiled_gen12_rc_ccs [874.687699] Subtest pipe-C-crc-primary-basic-y_tiled_gen12_rc_ccs: SKIP (0.001s) [875.085765] [106/142] (136s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-plflip-blt) [875.397406] Starting subtest: psr-2p-scndscrn-indfb-plflip-blt [875.400216] Subtest psr-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s) [875.502347] [107/142] (135s left) syncobj_wait (single-wait-for-submit-submitted) [875.705817] Starting subtest: single-wait-for-submit-submitted [875.809192] Subtest single-wait-for-submit-submitted: SUCCESS (0.102s) [875.901630] [108/142] (135s left) gem_exec_balancer (bonded-sync) [876.124605] Starting subtest: bonded-sync [879.937629] Subtest bonded-sync: SUCCESS (3.810s) [883.918290] [109/142] (127s left) i915_pm_rpm (modeset-lpsp-stress) [886.031221] Starting subtest: modeset-lpsp-stress [886.547035] Subtest modeset-lpsp-stress: SKIP (0.514s) [887.025387] [110/142] (124s left) kms_atomic_transition (plane-toggle-modeset-transition) [887.323488] Starting subtest: plane-toggle-modeset-transition [887.324780] Starting dynamic subtest: pipe-A-DP-1 [892.744898] Dynamic subtest pipe-A-DP-1: SUCCESS (5.420s) [892.808787] Starting dynamic subtest: pipe-B-DP-1 [898.207160] Dynamic subtest pipe-B-DP-1: SUCCESS (5.450s) [898.213308] Subtest plane-toggle-modeset-transition: SUCCESS (10.893s) [898.545649] [111/142] (112s left) gem_ctx_param (invalid-ctx-set) [898.758456] Starting subtest: invalid-ctx-set [898.760168] Subtest invalid-ctx-set: SUCCESS (0.000s) [898.845180] [112/142] (112s left) kms_plane_scaling (plane-upscale-with-rotation-factor-0-25) [899.140469] Starting subtest: plane-upscale-with-rotation-factor-0-25 [899.141790] Starting dynamic subtest: pipe-A-DP-1 [899.206228] Dynamic subtest pipe-A-DP-1: SKIP (0.063s) [899.230448] Starting dynamic subtest: pipe-B-DP-1 [899.552683] Dynamic subtest pipe-B-DP-1: SKIP (0.340s) [899.585413] Starting dynamic subtest: pipe-C-DP-1 [899.910446] Dynamic subtest pipe-C-DP-1: SKIP (0.354s) [899.913679] Subtest plane-upscale-with-rotation-factor-0-25: SKIP (0.771s) [900.316122] [113/142] (111s left) v3d/v3d_submit_cl (bad-flag) [900.572580] Subtest bad-flag: SKIP (0.000s) [900.648617] [114/142] (110s left) kms_vblank (invalid) [900.942329] Starting subtest: invalid [901.203239] Subtest invalid: SUCCESS (0.260s) [901.555418] [115/142] (110s left) kms_cursor_edge_walk (128x128-left-edge) [901.855348] Starting subtest: 128x128-left-edge [901.856643] Starting dynamic subtest: pipe-A-DP-1 [905.484974] Dynamic subtest pipe-A-DP-1: SUCCESS (3.628s) [905.509077] Starting dynamic subtest: pipe-C-DP-1 [908.991635] Dynamic subtest pipe-C-DP-1: SUCCESS (3.500s) [908.993524] Subtest 128x128-left-edge: SUCCESS (7.138s) [909.314074] [116/142] (102s left) gem_exec_schedule (promotion) [909.547448] Starting subtest: promotion [909.552284] Starting dynamic subtest: rcs0 [909.702722] Dynamic subtest rcs0: SUCCESS (0.151s) [909.730418] Starting dynamic subtest: bcs0 [909.867940] Dynamic subtest bcs0: SUCCESS (0.159s) [909.895811] Starting dynamic subtest: vcs0 [910.011355] Dynamic subtest vcs0: SUCCESS (0.141s) [910.038924] Starting dynamic subtest: vecs0 [910.153620] Dynamic subtest vecs0: SUCCESS (0.137s) [910.154948] Subtest promotion: SUCCESS (0.606s) [910.512579] [117/142] (101s left) vc4/vc4_dmabuf_poll (poll-write-waits-until-write-done) [910.768505] Subtest poll-write-waits-until-write-done: SKIP (0.000s) [910.844676] [118/142] (100s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-pwrite) [911.147738] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-pwrite [913.217347] Subtest fbc-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (2.071s) [913.362216] [119/142] ( 98s left) gem_userptr_blits (map-fixed-invalidate-overlap) [913.603627] Starting subtest: map-fixed-invalidate-overlap [913.605150] Starting dynamic subtest: gtt [913.967662] Dynamic subtest gtt: SUCCESS (0.361s) [913.994348] Starting dynamic subtest: wb [914.250315] Dynamic subtest wb: SUCCESS (0.280s) [914.273408] Starting dynamic subtest: wc [914.520885] Dynamic subtest wc: SUCCESS (0.268s) [914.544332] Starting dynamic subtest: uc [914.797130] Dynamic subtest uc: SUCCESS (0.273s) [914.798596] Subtest map-fixed-invalidate-overlap: SUCCESS (1.194s) [914.929602] [120/142] ( 96s left) kms_pipe_crc_basic (bad-source) [915.233950] Starting subtest: bad-source [915.235312] Subtest bad-source: SUCCESS (0.001s) [915.328274] [121/142] ( 96s left) kms_ccs (pipe-a-crc-primary-rotation-180-y_tiled_gen12_rc_ccs_cc) [915.735448] Starting subtest: pipe-A-crc-primary-rotation-180-y_tiled_gen12_rc_ccs_cc [915.738311] Subtest pipe-A-crc-primary-rotation-180-y_tiled_gen12_rc_ccs_cc: SKIP (0.001s) [916.309259] [122/142] ( 95s left) gem_set_tiling_vs_blt (tiled-to-untiled) [916.523528] Starting subtest: tiled-to-untiled [916.641227] Subtest tiled-to-untiled: SUCCESS (0.111s) [916.759691] [123/142] ( 95s left) kms_atomic_interruptible (legacy-pageflip) [917.060961] Starting subtest: legacy-pageflip [917.062801] Starting dynamic subtest: DP-1-pipe-A [923.615845] Dynamic subtest DP-1-pipe-A: SUCCESS (6.550s) [923.617250] Subtest legacy-pageflip: SUCCESS (6.556s) [923.970670] [124/142] ( 88s left) kms_ccs (pipe-a-crc-primary-basic-4_tiled_dg2_rc_ccs_cc) [924.386735] Starting subtest: pipe-A-crc-primary-basic-4_tiled_dg2_rc_ccs_cc [924.389629] Subtest pipe-A-crc-primary-basic-4_tiled_dg2_rc_ccs_cc: SKIP (0.001s) [924.978499] [125/142] ( 87s left) kms_psr (sprite_blt) [925.181194] Subtest sprite_blt: SKIP (0.000s) [925.243820] [126/142] ( 86s left) kms_ccs (pipe-c-bad-rotation-90-4_tiled_mtl_rc_ccs) [925.860693] Starting subtest: pipe-C-bad-rotation-90-4_tiled_mtl_rc_ccs [925.863406] Subtest pipe-C-bad-rotation-90-4_tiled_mtl_rc_ccs: SKIP (0.000s) [926.295124] [127/142] ( 85s left) core_getclient [926.577968] [128/142] ( 85s left) kms_ccs (pipe-d-random-ccs-data-4_tiled_dg2_mc_ccs) [927.261753] Subtest pipe-D-random-ccs-data-4_tiled_dg2_mc_ccs: SKIP (0.000s) [927.580547] [129/142] ( 84s left) fbdev (write) [927.788461] Starting subtest: write [928.522594] Subtest write: SUCCESS (0.733s) [928.597116] [130/142] ( 83s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite) [928.901321] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite [928.904336] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s) [928.997504] [131/142] ( 83s left) syncobj_timeline (host-signal-ordered) [929.205766] Starting subtest: host-signal-ordered [929.207327] Subtest host-signal-ordered: SUCCESS (0.002s) [929.296924] [132/142] ( 83s left) gem_exec_parallel (basic) [929.523484] Starting subtest: basic [929.527992] Starting dynamic subtest: rcs0 [933.104154] Dynamic subtest rcs0: SUCCESS (3.579s) [933.129340] Starting dynamic subtest: bcs0 [936.806567] Dynamic subtest bcs0: SUCCESS (3.699s) [936.832575] Starting dynamic subtest: vcs0 [940.280161] Dynamic subtest vcs0: SUCCESS (3.471s) [940.305575] Starting dynamic subtest: vecs0 [943.718514] Dynamic subtest vecs0: SUCCESS (3.436s) [943.721310] Subtest basic: SUCCESS (14.197s) [943.932999] [133/142] ( 68s left) kms_ccs (pipe-c-random-ccs-data-4_tiled_dg2_rc_ccs_cc) [944.566399] Starting subtest: pipe-C-random-ccs-data-4_tiled_dg2_rc_ccs_cc [944.571422] Subtest pipe-C-random-ccs-data-4_tiled_dg2_rc_ccs_cc: SKIP (0.000s) [944.933225] [134/142] ( 67s left) gem_exec_reloc (basic-write-wc-noreloc) [945.148440] Starting subtest: basic-write-wc-noreloc [945.161698] Subtest basic-write-wc-noreloc: SUCCESS (0.012s) [945.349586] [135/142] ( 67s left) prime_udl [945.598620] [136/142] ( 66s left) kms_ccs (pipe-d-bad-rotation-90-4_tiled_mtl_rc_ccs) [946.259251] Subtest pipe-D-bad-rotation-90-4_tiled_mtl_rc_ccs: SKIP (0.000s) [946.585413] [137/142] ( 66s left) kms_ccs (pipe-b-bad-rotation-90-4_tiled_dg2_rc_ccs_cc) [947.059971] Starting subtest: pipe-B-bad-rotation-90-4_tiled_dg2_rc_ccs_cc [947.062793] Subtest pipe-B-bad-rotation-90-4_tiled_dg2_rc_ccs_cc: SKIP (0.000s) [947.571626] [138/142] ( 65s left) kms_big_fb (yf-tiled-32bpp-rotate-270) [947.890039] Starting subtest: yf-tiled-32bpp-rotate-270 [952.909807] Subtest yf-tiled-32bpp-rotate-270: SUCCESS (5.019s) [953.440735] [139/142] ( 59s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-pwrite) [953.755041] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-pwrite [953.757532] Subtest fbc-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s) [953.855737] [140/142] ( 58s left) kms_big_fb (x-tiled-8bpp-rotate-0) [954.181248] Starting subtest: x-tiled-8bpp-rotate-0 [957.227200] Subtest x-tiled-8bpp-rotate-0: SUCCESS (3.044s) [957.475193] [141/142] ( 55s left) v3d/v3d_submit_csd (bad-multisync-extension) [957.744832] Subtest bad-multisync-extension: SKIP (0.000s) [957.821821] [142/142] ( 55s left) syncobj_wait (invalid-multi-wait-unsubmitted) [958.022639] Starting subtest: invalid-multi-wait-unsubmitted [958.024403] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s) [958.117459] Closing watchdogs Done.